Well, I'm back from vaca, so started looking at this again. Unfortunately, even with Java 7, now I'm having trouble seeing it even once in any of my hundreds of runs. Bleh. Guess a crystal in my virtual machine shifted while I was away. Though on second glance, it also appears to be happening less often on Uwe's build machine as well.
On Jun 6, 2012, at 6:57 AM, [email protected] wrote: > Build: > http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java7-64/251/ > > 1 tests failed. > REGRESSION: org.apache.solr.handler.TestReplicationHandler.test > > Error Message: > expected:<494> but was:<0> > > Stack Trace: > java.lang.AssertionError: expected:<494> but was:<0> > at > __randomizedtesting.SeedInfo.seed([619712C78B3D9498:E9C32D1D25C1F960]:0) > at org.junit.Assert.fail(Assert.java:93) > at org.junit.Assert.failNotEquals(Assert.java:647) > at org.junit.Assert.assertEquals(Assert.java:128) > at org.junit.Assert.assertEquals(Assert.java:472) > at org.junit.Assert.assertEquals(Assert.java:456) > at > org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:716) > at > org.apache.solr.handler.TestReplicationHandler.test(TestReplicationHandler.java:254) > 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 > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1969) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.access$1100(RandomizedRunner.java:132) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:814) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:875) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:889) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) > at > org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:32) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) > at > org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:821) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.access$700(RandomizedRunner.java:132) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$3$1.run(RandomizedRunner.java:669) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:695) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:734) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:745) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38) > at > org.apache.lucene.util.TestRuleIcuHack$1.evaluate(TestRuleIcuHack.java:51) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) > at > org.apache.lucene.util.TestRuleNoInstanceHooksOverrides$1.evaluate(TestRuleNoInstanceHooksOverrides.java:53) > at > org.apache.lucene.util.TestRuleNoStaticHooksShadowing$1.evaluate(TestRuleNoStaticHooksShadowing.java:52) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:36) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:56) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:605) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.access$400(RandomizedRunner.java:132) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:551) > > > > > Build Log: > [...truncated 16425 lines...] > [junit4] 2> 60131 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_Lucene40_0.tip} > status=0 QTime=0 > [junit4] 2> 60137 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9.si} > status=0 QTime=0 > [junit4] 2> 60143 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9.fdx} > status=0 QTime=0 > [junit4] 2> 60149 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=segments_g} > status=0 QTime=0 > [junit4] 2> 60157 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_Lucene40_0.frq} > status=0 QTime=0 > [junit4] 2> 60162 T226 oash.SnapPuller.fetchLatestIndex Total time taken > for download : 0 secs > [junit4] 2> 60164 T226 oash.SnapPuller.copyAFile SEVERE Unable to move > index file from: > .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064122\_9_Lucene40_0.tim > to: > C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064050\_9_Lucene40_0.tim > Trying to do a copy > [junit4] 2> 60165 T226 oash.SnapPuller.copyAFile SEVERE Unable to copy > index file from: > .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064122\_9_Lucene40_0.tim > to: > C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064050\_9_Lucene40_0.tim > java.io.FileNotFoundException: > C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064050\_9_Lucene40_0.tim > (The system cannot find the path specified) > [junit4] 2> at java.io.FileOutputStream.open(Native Method) > [junit4] 2> at > java.io.FileOutputStream.<init>(FileOutputStream.java:212) > [junit4] 2> at > java.io.FileOutputStream.<init>(FileOutputStream.java:165) > [junit4] 2> at > org.apache.solr.util.FileUtils.copyFile(FileUtils.java:48) > [junit4] 2> at > org.apache.solr.handler.SnapPuller.copyAFile(SnapPuller.java:654) > [junit4] 2> at > org.apache.solr.handler.SnapPuller.copyIndexFiles(SnapPuller.java:691) > [junit4] 2> at > org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:371) > [junit4] 2> at > org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:298) > [junit4] 2> at > org.apache.solr.handler.SnapPuller$1.run(SnapPuller.java:169) > [junit4] 2> at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) > [junit4] 2> at > java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351) > [junit4] 2> at > java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178) > [junit4] 2> at > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178) > [junit4] 2> at > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) > [junit4] 2> at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) > [junit4] 2> at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) > [junit4] 2> at java.lang.Thread.run(Thread.java:722) > [junit4] 2> > [junit4] 2> 60239 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 60362 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 60486 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 60609 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 60732 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 60856 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 60980 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 61104 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 61227 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 61232 T209 C19 REQ [collection1] webapp=/solr > path=/replication params={command=indexversion&wt=javabin} status=0 QTime=0 > [junit4] 2> 61233 T226 oash.SnapPuller.fetchLatestIndex Master's > generation: 16 > [junit4] 2> 61233 T226 oash.SnapPuller.fetchLatestIndex Slave's > generation: 15 > [junit4] 2> 61233 T226 oash.SnapPuller.fetchLatestIndex Starting > replication process > [junit4] 2> 61239 T209 C19 REQ [collection1] webapp=/solr > path=/replication params={command=filelist&wt=javabin&generation=16} status=0 > QTime=3 > [junit4] 2> 61240 T226 oash.SnapPuller.fetchLatestIndex Number of files > in latest index in master: 10 > [junit4] 2> 61243 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_Lucene40_0.tim} > status=0 QTime=0 > [junit4] 2> 61249 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_nrm.cfe} > status=0 QTime=0 > [junit4] 2> 61255 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9.fnm} > status=0 QTime=0 > [junit4] 2> 61261 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_nrm.cfs} > status=0 QTime=0 > [junit4] 2> 61267 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9.fdt} > status=0 QTime=0 > [junit4] 2> 61273 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_Lucene40_0.tip} > status=0 QTime=0 > [junit4] 2> 61281 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9.si} > status=0 QTime=0 > [junit4] 2> 61287 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9.fdx} > status=0 QTime=0 > [junit4] 2> 61293 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=segments_g} > status=0 QTime=0 > [junit4] 2> 61300 T209 C19 REQ [collection1] webapp=/solr > path=/replication > params={command=filecontent&checksum=true&generation=16&wt=filestream&file=_9_Lucene40_0.frq} > status=0 QTime=0 > [junit4] 2> 61306 T226 oash.SnapPuller.fetchLatestIndex Total time taken > for download : 0 secs > [junit4] 2> 61308 T226 oash.SnapPuller.copyAFile SEVERE Unable to move > index file from: > .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064123\_9_Lucene40_0.tim > to: > C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064050\_9_Lucene40_0.tim > Trying to do a copy > [junit4] 2> 61309 T226 oash.SnapPuller.copyAFile SEVERE Unable to copy > index file from: > .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064123\_9_Lucene40_0.tim > to: > C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064050\_9_Lucene40_0.tim > java.io.FileNotFoundException: > C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1338979222620\slave\data\index.20120606064050\_9_Lucene40_0.tim > (The system cannot find the path specified) > [junit4] 2> at java.io.FileOutputStream.open(Native Method) > [junit4] 2> at > java.io.FileOutputStream.<init>(FileOutputStream.java:212) > [junit4] 2> at > java.io.FileOutputStream.<init>(FileOutputStream.java:165) > [junit4] 2> at > org.apache.solr.util.FileUtils.copyFile(FileUtils.java:48) > [junit4] 2> at > org.apache.solr.handler.SnapPuller.copyAFile(SnapPuller.java:654) > [junit4] 2> at > org.apache.solr.handler.SnapPuller.copyIndexFiles(SnapPuller.java:691) > [junit4] 2> at > org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:371) > [junit4] 2> at > org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:298) > [junit4] 2> at > org.apache.solr.handler.SnapPuller$1.run(SnapPuller.java:169) > [junit4] 2> at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) > [junit4] 2> at > java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351) > [junit4] 2> at > java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178) > [junit4] 2> at > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178) > [junit4] 2> at > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) > [junit4] 2> at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) > [junit4] 2> at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) > [junit4] 2> at java.lang.Thread.run(Thread.java:722) > [junit4] 2> > [junit4] 2> 61351 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 61474 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 61599 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 > [junit4] 2> 61721 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 61845 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 > [junit4] 2> 61967 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 62091 T218 C20 REQ [collection1] webapp=/solr path=/select > params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 > [junit4] 2> 62213 T49 oas.SolrTestCaseJ4.tearDown ###Ending test > [junit4] 1> replicate slave to master > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=TestReplicationHandler -Dtests.method=test > -Dtests.seed=619712C78B3D9498 -Dtests.locale=ca > -Dtests.timezone=America/Kentucky/Louisville -Dargs="-Dfile.encoding=Cp1252" > [junit4] 1> > [junit4] 2> > [junit4] > (@AfterClass output) > [junit4] 2> 62268 T49 oasc.CoreContainer.shutdown Shutting down > CoreContainer instance=835832470 > [junit4] 2> 62269 T49 oasc.SolrCore.close [collection1] CLOSING SolrCore > org.apache.solr.core.SolrCore@38134991 > [junit4] 2> 62269 T49 oasc.SolrCore.closeSearcher [collection1] Closing > main searcher on request. > [junit4] 2> 62270 T49 oasu.DirectUpdateHandler2.close closing > DirectUpdateHandler2{commits=2,autocommits=0,soft > autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=494,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0} > [junit4] 2> 62274 T49 oejsh.ContextHandler.doStop stopped > o.e.j.s.ServletContextHandler{/solr,null} > [junit4] 2> 62328 T49 oasc.CoreContainer.shutdown Shutting down > CoreContainer instance=46470462 > [junit4] 2> 62328 T49 oasc.SolrCore.close [collection1] CLOSING SolrCore > org.apache.solr.core.SolrCore@5142b8b > [junit4] 2> 62329 T49 oasc.SolrCore.closeSearcher [collection1] Closing > main searcher on request. > [junit4] 2> 62331 T49 oasu.DirectUpdateHandler2.close closing > DirectUpdateHandler2{commits=1,autocommits=0,soft > autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0} > [junit4] 2> 62334 T49 oejsh.ContextHandler.doStop stopped > o.e.j.s.ServletContextHandler{/solr,null} > [junit4] 2> 62409 T49 oas.SolrTestCaseJ4.deleteCore ###deleteCore > [junit4] 2> NOTE: test params are: codec=Lucene40: {}, > sim=DefaultSimilarity, locale=ca, timezone=America/Kentucky/Louisville > [junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 > (64-bit)/cpus=2,threads=1,free=59448456,total=213319680 > [junit4] 2> NOTE: All tests run in this JVM: > [SolrCoreCheckLockOnStartupTest, ShowFileRequestHandlerTest, > SnowballPorterFilterFactoryTest, TestSystemIdResolver, > StandardRequestHandlerTest, TestCJKWidthFilterFactory, > TestIrishLowerCaseFilterFactory, TestXIncludeConfig, TestNGramFilters, > TestSynonymFilterFactory, TestDelimitedPayloadTokenFilterFactory, > RequestHandlersTest, TestMultiWordSynonyms, TestJapaneseTokenizerFactory, > SampleTest, TestGalicianStemFilterFactory, SuggesterWFSTTest, > TestHungarianLightStemFilterFactory, IndexReaderFactoryTest, > TestHyphenationCompoundWordTokenFilterFactory, TestReplicationHandler] > [junit4] 2> > [junit4] Completed in 62.42s, 1 test, 1 failure <<< FAILURES! > [...truncated 1068 lines...] > > [...truncated 17594 lines...] > > [...truncated 17594 lines...] > > [...truncated 17594 lines...] > > [...truncated 17594 lines...] > > [...truncated 17594 lines...] > > > > --------------------------------------------------------------------- > To unsubscribe, e-mail: [email protected] > For additional commands, e-mail: [email protected] - Mark Miller lucidimagination.com --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
