You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by je...@sd-datasolutions.de on 2012/06/12 11:02:59 UTC
[JENKINS] Lucene-Solr-trunk-Windows-Java7-64 - Build # 298 - Still
Failing!
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java7-64/298/
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.test
Error Message:
expected:<498> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<498> but was:<0>
at __randomizedtesting.SeedInfo.seed([E3330B2D2BC7600D:6B6734F7853B0DF5]: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.doTestIndexAndConfigReplication(TestReplicationHandler.java:391)
at org.apache.solr.handler.TestReplicationHandler.test(TestReplicationHandler.java:250)
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 13637 lines...]
[junit4] 2> 38292 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tip} status=0 QTime=0
[junit4] 2> 38296 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=segments_7} status=0 QTime=0
[junit4] 2> 38301 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tim} status=0 QTime=0
[junit4] 2> 38305 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.frq} status=0 QTime=0
[junit4] 2> 38308 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.si} status=0 QTime=0
[junit4] 2> 38314 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fnm} status=0 QTime=0
[junit4] 2> 38314 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 38319 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdt} status=0 QTime=0
[junit4] 2> 38320 T82 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 38322 T82 oash.SnapPuller.copyAFile SEVERE Unable to move index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234355\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326\_3_nrm.cfe Trying to do a copy
[junit4] 2> 38322 T82 oash.SnapPuller.copyAFile SEVERE The index directory does not exist: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326 dirs found: [index.20120611234355, index.properties, replication.properties]
[junit4] 2> 38322 T82 oash.SnapPuller.copyAFile SEVERE Unable to copy index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234355\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326\_3_nrm.cfe java.io.FileNotFoundException: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326\_3_nrm.cfe (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:703)
[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> 38427 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 38538 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 38647 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 38760 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 38871 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 38985 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39099 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39195 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin} status=0 QTime=0
[junit4] 2> 39196 T82 oash.SnapPuller.fetchLatestIndex Master's generation: 7
[junit4] 2> 39196 T82 oash.SnapPuller.fetchLatestIndex Slave's generation: 6
[junit4] 2> 39197 T82 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 39201 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filelist&wt=javabin&generation=7} status=0 QTime=0
[junit4] 2> 39202 T82 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 39205 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_nrm.cfe} status=0 QTime=0
[junit4] 2> 39211 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdx} status=0 QTime=0
[junit4] 2> 39211 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39214 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_nrm.cfs} status=0 QTime=0
[junit4] 2> 39218 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tip} status=0 QTime=0
[junit4] 2> 39224 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=segments_7} status=0 QTime=0
[junit4] 2> 39228 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tim} status=0 QTime=0
[junit4] 2> 39232 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.frq} status=0 QTime=0
[junit4] 2> 39236 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.si} status=0 QTime=0
[junit4] 2> 39241 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fnm} status=0 QTime=0
[junit4] 2> 39245 T65 C4 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdt} status=0 QTime=0
[junit4] 2> 39246 T82 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 39247 T82 oash.SnapPuller.copyAFile SEVERE Unable to move index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234356\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326\_3_nrm.cfe Trying to do a copy
[junit4] 2> 39248 T82 oash.SnapPuller.copyAFile SEVERE The index directory does not exist: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326 dirs found: [index.20120611234356, index.properties, replication.properties]
[junit4] 2> 39248 T82 oash.SnapPuller.copyAFile SEVERE Unable to copy index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234356\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326\_3_nrm.cfe java.io.FileNotFoundException: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339490598463\slave\data\index.20120611234326\_3_nrm.cfe (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:703)
[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> 39321 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39432 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39543 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39655 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39765 T74 C5 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 39874 T14 oas.SolrTestCaseJ4.tearDown ###Ending test
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.method=test -Dtests.seed=E3330B2D2BC7600D -Dtests.locale=es_UY -Dtests.timezone=US/Aleutian -Dargs="-Dfile.encoding=Cp1252"
[junit4] 2>
[junit4] > (@AfterClass output)
[junit4] 2> 39922 T14 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=73126668
[junit4] 2> 39922 T14 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6da6e106
[junit4] 2> 39923 T14 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 39924 T14 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=997,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=0}
[junit4] 2> 39926 T14 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 40001 T14 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1619415306
[junit4] 2> 40002 T14 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@88dfd83
[junit4] 2> 40002 T14 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 40003 T14 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=8,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 40004 T14 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 40069 T14 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40: {}, sim=DefaultSimilarity, locale=es_UY, timezone=US/Aleutian
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 (64-bit)/cpus=2,threads=1,free=98667576,total=145293312
[junit4] 2> NOTE: All tests run in this JVM: [TestIndexingPerformance, TestArabicFilters, TestBrazilianStemFilterFactory, TestReplicationHandler]
[junit4] 2>
[junit4] Completed in 40.10s, 1 test, 1 failure <<< FAILURES!
[...truncated 1128 lines...]
[...truncated 14866 lines...]
[...truncated 14866 lines...]
[...truncated 14866 lines...]
[...truncated 14866 lines...]
[...truncated 14866 lines...]
Re: [JENKINS] Lucene-Solr-trunk-Windows-Java7-64 - Build # 300 -
Still Failing!
Posted by Sami Siren <ss...@gmail.com>.
I am working on it, I have identified at least one problem which is
that the overseer is killed too often in that test, i'll let the test
run locally for a bit and if everything looks good commit a fix
tomorrow.
--
Sami Siren
On Tue, Jun 12, 2012 at 6:32 PM, Mark Miller <ma...@gmail.com> wrote:
> While working on the collections api, I have seen this on the odd occasion locally as well.
>
> On Jun 12, 2012, at 10:43 AM, jenkins@sd-datasolutions.de wrote:
>
>> Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java7-64/300/
>>
>> 1 tests failed.
>> FAILED: org.apache.solr.cloud.OverseerTest.testShardLeaderChange
>>
>> Error Message:
>> Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
>>
>> Stack Trace:
>> org.junit.ComparisonFailure: Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
>> at __randomizedtesting.SeedInfo.seed([195A5E746C7F55C0:C709D98376E7A031]:0)
>> at org.junit.Assert.assertEquals(Assert.java:125)
>> at org.apache.solr.cloud.OverseerTest.verifyShardLeader(OverseerTest.java:522)
>> at org.apache.solr.cloud.OverseerTest.testShardLeaderChange(OverseerTest.java:677)
>> 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 11002 lines...]
>> [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
>> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
>> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
>> [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
>> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
>> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
>> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
>> [junit4] 2> at java.lang.Thread.run(Thread.java:722)
>> [junit4] 2>
>> [junit4] 2> 4792 T1150 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87786223260336139-127.0.0.1:57511_solr-n_0000000008) am no longer a leader.
>> [junit4] 2> 4937 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
>> [junit4] 2> 4963 T1153 oasc.Overseer$CloudStateUpdater.run WARNING Overseer cannot talk to ZK
>> [junit4] 2> 4964 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b000c
>> [junit4] 2> 4981 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57551 which had sessionid 0x137e11add9b000c
>> [junit4] 2> 4982 T1127 oaz.ZooKeeper.close Session: 0x137e11add9b000c closed
>> [junit4] 2> 4982 T1127 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57511/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7d16b6ef
>> [junit4] 2> 4982 T1152 oaz.ClientCnxn$EventThread.run EventThread shut down
>> [junit4] 2> 4982 T1154 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57511
>> [junit4] 2> 4982 T1154 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:57511, initiating session
>> [junit4] 2> 4982 T1117 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57555
>> [junit4] 2> 4982 T1117 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57555
>> [junit4] 2> 5007 T1119 oazs.NIOServerCnxn.finishSessionInit Established session 0x137e11add9b000d with negotiated timeout 10000 for client /127.0.0.1:57555
>> [junit4] 2> 5008 T1154 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:57511, sessionid = 0x137e11add9b000d, negotiated timeout = 10000
>> [junit4] 2> 5008 T1155 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d16b6ef name:ZooKeeperConnection Watcher:127.0.0.1:57511/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
>> [junit4] 2> 5028 T1127 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
>> [junit4] 2> 5047 T1127 oasc.Overseer.<init> Overseer (id=87786223260336141-127.0.0.1:57511_solr-n_0000000012) starting
>> [junit4] 2> 5047 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
>> [junit4] 2> 5060 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
>> [junit4] 2> 5069 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0xa zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
>> [junit4] 2> 5091 T1156 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
>> [junit4] 2> 5788 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
>> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
>> [junit4] 2>
>> [junit4] 2> 6956 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
>> [junit4] 2> 7895 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
>> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
>> [junit4] 2>
>> [junit4] 2> 9652 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
>> [junit4] 2> 10640 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
>> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
>> [junit4] 2>
>> [junit4] 2> 12479 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
>> [junit4] 2> 13046 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b000d
>> [junit4] 2> 13065 T1117 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e11add9b000d, likely client has closed socket
>> [junit4] 2> 13065 T1127 oaz.ZooKeeper.close Session: 0x137e11add9b000d closed
>> [junit4] 2> 13065 T1155 oaz.ClientCnxn$EventThread.run EventThread shut down
>> [junit4] 2> 13066 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57555 which had sessionid 0x137e11add9b000d
>> [junit4] 2> 13069 T1122 oascc.ZkStateReader$3.process Updating live nodes
>> [junit4] 2> 13070 T1140 oascc.ZkStateReader$3.process Updating live nodes
>> [junit4] 2> 13071 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b0008
>> [junit4] 2> 13073 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57538 which had sessionid 0x137e11add9b0008
>> [junit4] 2> 13073 T1140 oaz.ClientCnxn$EventThread.run EventThread shut down
>> [junit4] 2> 13073 T1115 oaz.ZooKeeper.close Session: 0x137e11add9b0008 closed
>> [junit4] 2> 13074 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b0000
>> [junit4] 2> 13076 T1117 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e11add9b0000, likely client has closed socket
>> [junit4] 2> 13076 T1115 oaz.ZooKeeper.close Session: 0x137e11add9b0000 closed
>> [junit4] 2> 13076 T1122 oaz.ClientCnxn$EventThread.run EventThread shut down
>> [junit4] 2> 13076 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57514 which had sessionid 0x137e11add9b0000
>> [junit4] 2> 13079 T1120 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
>> [junit4] 2> 13079 T1119 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
>> [junit4] 2> 13080 T1115 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
>> [junit4] 2> 13171 T1156 oasc.Overseer$CloudStateUpdater.amILeader WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
>> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
>> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
>> [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
>> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
>> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
>> [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
>> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
>> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
>> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
>> [junit4] 2> at java.lang.Thread.run(Thread.java:722)
>> [junit4] 2>
>> [junit4] 2> 13171 T1156 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87786223260336141-127.0.0.1:57511_solr-n_0000000012) am no longer a leader.
>> [junit4] 2> 13422 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
>> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
>> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
>> [junit4] 2>
>> [junit4] 2> 14960 T1118 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
>> [junit4] 2> 15006 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
>> [junit4] 2> 15559 T1117 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
>> [junit4] 2> 15561 T1115 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
>> [junit4] 2> 15561 T1115 oas.SolrTestCaseJ4.tearDown ###Ending testShardLeaderChange
>> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.method=testShardLeaderChange -Dtests.seed=195A5E746C7F55C0 -Dtests.locale=es_CR -Dtests.timezone=Asia/Colombo -Dargs="-Dfile.encoding=Cp1252"
>> [junit4] 2>
>> [junit4] > (@AfterClass output)
>> [junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.initCore ####initCore
>> [junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.initCore ####initCore end
>> [junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.deleteCore ###deleteCore
>> [junit4] 2> NOTE: test params are: codec=Lucene40: {}, sim=DefaultSimilarity, locale=es_CR, timezone=Asia/Colombo
>> [junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 (64-bit)/cpus=2,threads=1,free=113734152,total=259325952
>> [junit4] 2> NOTE: All tests run in this JVM: [UUIDFieldTest, LengthFilterTest, TestNGramFilters, IndexReaderFactoryTest, TestQueryUtils, OutputWriterTest, SolrRequestParserTest, DistributedQueryElevationComponentTest, MultiTermTest, TestDFRSimilarityFactory, CloudStateUpdateTest, TestCSVLoader, JSONWriterTest, SuggesterTSTTest, TestArbitraryIndexDir, TestRecovery, StatsComponentTest, TestSpanishLightStemFilterFactory, TestPropInject, LeaderElectionTest, TestGermanNormalizationFilterFactory, URLClassifyProcessorTest, TestPseudoReturnFields, TestSolrCoreProperties, PeerSyncTest, DisMaxRequestHandlerTest, TestPortugueseStemFilterFactory, TestDistributedSearch, TestGreekStemFilterFactory, TestGalicianStemFilterFactory, BadIndexSchemaTest, TestShingleFilterFactory, PrimitiveFieldTypeTest, SignatureUpdateProcessorFactoryTest, RequestHandlersTest, TestGalicianMinimalStemFilterFactory, DocumentAnalysisRequestHandlerTest, TestTrie, DirectSolrSpellCheckerTest, LeaderElectionIntegrationTest, TestMappingCharFilterFactory, DistanceFunctionTest, TestBrazilianStemFilterFactory, UniqFieldsUpdateProcessorFactoryTest, TestJapaneseBaseFormFilterFactory, TestFaceting, DistributedTermsComponentTest, TestJmxIntegration, TestPHPSerializedResponseWriter, FileBasedSpellCheckerTest, TestBulgarianStemFilterFactory, SnowballPorterFilterFactoryTest, TestGermanStemFilterFactory, FullSolrCloudTest, TimeZoneUtilsTest, LukeRequestHandlerTest, SystemInfoHandlerTest, CoreAdminHandlerTest, TestLMJelinekMercerSimilarityFactory, TestGroupingSearch, TestRemoteStreaming, SolrIndexConfigTest, TestItalianLightStemFilterFactory, CSVRequestHandlerTest, FieldMutatingUpdateProcessorTest, TestRussianLightStemFilterFactory, TestPluginEnable, TestJmxMonitoredMap, TestTurkishLowerCaseFilterFactory, TestStemmerOverrideFilterFactory, ConvertedLegacyTest, TestWriterPerf, TestGermanMinimalStemFilterFactory, TestExtendedDismaxParser, TestHyphenationCompoundWordTokenFilterFactory, TestFinnishLightStemFilterFactory, FieldAnalysisRequestHandlerTest, TestIndexingPerformance, FastVectorHighlighterTest, AlternateDirectoryTest, TestPersianNormalizationFilterFactory, BinaryUpdateRequestHandlerTest, CacheHeaderTest, TestCJKBigramFilterFactory, TestDocSet, TestNumberUtils, XmlUpdateRequestHandlerTest, TestRemoveDuplicatesTokenFilterFactory, TestSolrXMLSerializer, TestUpdate, TestBadConfig, MBeansHandlerTest, NoCacheHeaderTest, BasicZkTest, TestPatternReplaceCharFilterFactory, UpdateParamsTest, TestCapitalizationFilterFactory, TestDelimitedPayloadTokenFilterFactory, NotRequiredUniqueKeyTest, SuggesterTest, OverseerTest]
>> [junit4] 2>
>> [junit4] Completed in 76.66s, 8 tests, 1 failure <<< FAILURES!
>> [...truncated 831 lines...]
>>
>> [...truncated 11934 lines...]
>>
>> [...truncated 11934 lines...]
>>
>> [...truncated 11934 lines...]
>>
>> [...truncated 11934 lines...]
>>
>> [...truncated 11934 lines...]
>>
>>
>>
>> ---------------------------------------------------------------------
>> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
>> For additional commands, e-mail: dev-help@lucene.apache.org
>
> - Mark Miller
> lucidimagination.com
>
>
>
>
>
>
>
>
>
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
>
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
Re: [JENKINS] Lucene-Solr-trunk-Windows-Java7-64 - Build # 300 - Still Failing!
Posted by Mark Miller <ma...@gmail.com>.
While working on the collections api, I have seen this on the odd occasion locally as well.
On Jun 12, 2012, at 10:43 AM, jenkins@sd-datasolutions.de wrote:
> Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java7-64/300/
>
> 1 tests failed.
> FAILED: org.apache.solr.cloud.OverseerTest.testShardLeaderChange
>
> Error Message:
> Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
>
> Stack Trace:
> org.junit.ComparisonFailure: Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
> at __randomizedtesting.SeedInfo.seed([195A5E746C7F55C0:C709D98376E7A031]:0)
> at org.junit.Assert.assertEquals(Assert.java:125)
> at org.apache.solr.cloud.OverseerTest.verifyShardLeader(OverseerTest.java:522)
> at org.apache.solr.cloud.OverseerTest.testShardLeaderChange(OverseerTest.java:677)
> 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 11002 lines...]
> [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
> [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
> [junit4] 2> at java.lang.Thread.run(Thread.java:722)
> [junit4] 2>
> [junit4] 2> 4792 T1150 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87786223260336139-127.0.0.1:57511_solr-n_0000000008) am no longer a leader.
> [junit4] 2> 4937 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
> [junit4] 2> 4963 T1153 oasc.Overseer$CloudStateUpdater.run WARNING Overseer cannot talk to ZK
> [junit4] 2> 4964 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b000c
> [junit4] 2> 4981 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57551 which had sessionid 0x137e11add9b000c
> [junit4] 2> 4982 T1127 oaz.ZooKeeper.close Session: 0x137e11add9b000c closed
> [junit4] 2> 4982 T1127 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57511/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7d16b6ef
> [junit4] 2> 4982 T1152 oaz.ClientCnxn$EventThread.run EventThread shut down
> [junit4] 2> 4982 T1154 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57511
> [junit4] 2> 4982 T1154 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:57511, initiating session
> [junit4] 2> 4982 T1117 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57555
> [junit4] 2> 4982 T1117 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57555
> [junit4] 2> 5007 T1119 oazs.NIOServerCnxn.finishSessionInit Established session 0x137e11add9b000d with negotiated timeout 10000 for client /127.0.0.1:57555
> [junit4] 2> 5008 T1154 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:57511, sessionid = 0x137e11add9b000d, negotiated timeout = 10000
> [junit4] 2> 5008 T1155 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d16b6ef name:ZooKeeperConnection Watcher:127.0.0.1:57511/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 5028 T1127 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
> [junit4] 2> 5047 T1127 oasc.Overseer.<init> Overseer (id=87786223260336141-127.0.0.1:57511_solr-n_0000000012) starting
> [junit4] 2> 5047 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
> [junit4] 2> 5060 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
> [junit4] 2> 5069 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0xa zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
> [junit4] 2> 5091 T1156 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
> [junit4] 2> 5788 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
> [junit4] 2>
> [junit4] 2> 6956 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
> [junit4] 2> 7895 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
> [junit4] 2>
> [junit4] 2> 9652 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
> [junit4] 2> 10640 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
> [junit4] 2>
> [junit4] 2> 12479 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
> [junit4] 2> 13046 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b000d
> [junit4] 2> 13065 T1117 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e11add9b000d, likely client has closed socket
> [junit4] 2> 13065 T1127 oaz.ZooKeeper.close Session: 0x137e11add9b000d closed
> [junit4] 2> 13065 T1155 oaz.ClientCnxn$EventThread.run EventThread shut down
> [junit4] 2> 13066 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57555 which had sessionid 0x137e11add9b000d
> [junit4] 2> 13069 T1122 oascc.ZkStateReader$3.process Updating live nodes
> [junit4] 2> 13070 T1140 oascc.ZkStateReader$3.process Updating live nodes
> [junit4] 2> 13071 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b0008
> [junit4] 2> 13073 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57538 which had sessionid 0x137e11add9b0008
> [junit4] 2> 13073 T1140 oaz.ClientCnxn$EventThread.run EventThread shut down
> [junit4] 2> 13073 T1115 oaz.ZooKeeper.close Session: 0x137e11add9b0008 closed
> [junit4] 2> 13074 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b0000
> [junit4] 2> 13076 T1117 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e11add9b0000, likely client has closed socket
> [junit4] 2> 13076 T1115 oaz.ZooKeeper.close Session: 0x137e11add9b0000 closed
> [junit4] 2> 13076 T1122 oaz.ClientCnxn$EventThread.run EventThread shut down
> [junit4] 2> 13076 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57514 which had sessionid 0x137e11add9b0000
> [junit4] 2> 13079 T1120 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
> [junit4] 2> 13079 T1119 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
> [junit4] 2> 13080 T1115 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
> [junit4] 2> 13171 T1156 oasc.Overseer$CloudStateUpdater.amILeader WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
> [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
> [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
> [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
> [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
> [junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
> [junit4] 2> at java.lang.Thread.run(Thread.java:722)
> [junit4] 2>
> [junit4] 2> 13171 T1156 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87786223260336141-127.0.0.1:57511_solr-n_0000000012) am no longer a leader.
> [junit4] 2> 13422 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
> [junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
> [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
> [junit4] 2>
> [junit4] 2> 14960 T1118 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
> [junit4] 2> 15006 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
> [junit4] 2> 15559 T1117 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
> [junit4] 2> 15561 T1115 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
> [junit4] 2> 15561 T1115 oas.SolrTestCaseJ4.tearDown ###Ending testShardLeaderChange
> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.method=testShardLeaderChange -Dtests.seed=195A5E746C7F55C0 -Dtests.locale=es_CR -Dtests.timezone=Asia/Colombo -Dargs="-Dfile.encoding=Cp1252"
> [junit4] 2>
> [junit4] > (@AfterClass output)
> [junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.initCore ####initCore
> [junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.initCore ####initCore end
> [junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.deleteCore ###deleteCore
> [junit4] 2> NOTE: test params are: codec=Lucene40: {}, sim=DefaultSimilarity, locale=es_CR, timezone=Asia/Colombo
> [junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 (64-bit)/cpus=2,threads=1,free=113734152,total=259325952
> [junit4] 2> NOTE: All tests run in this JVM: [UUIDFieldTest, LengthFilterTest, TestNGramFilters, IndexReaderFactoryTest, TestQueryUtils, OutputWriterTest, SolrRequestParserTest, DistributedQueryElevationComponentTest, MultiTermTest, TestDFRSimilarityFactory, CloudStateUpdateTest, TestCSVLoader, JSONWriterTest, SuggesterTSTTest, TestArbitraryIndexDir, TestRecovery, StatsComponentTest, TestSpanishLightStemFilterFactory, TestPropInject, LeaderElectionTest, TestGermanNormalizationFilterFactory, URLClassifyProcessorTest, TestPseudoReturnFields, TestSolrCoreProperties, PeerSyncTest, DisMaxRequestHandlerTest, TestPortugueseStemFilterFactory, TestDistributedSearch, TestGreekStemFilterFactory, TestGalicianStemFilterFactory, BadIndexSchemaTest, TestShingleFilterFactory, PrimitiveFieldTypeTest, SignatureUpdateProcessorFactoryTest, RequestHandlersTest, TestGalicianMinimalStemFilterFactory, DocumentAnalysisRequestHandlerTest, TestTrie, DirectSolrSpellCheckerTest, LeaderElectionIntegrationTest, TestMappingCharFilterFactory, DistanceFunctionTest, TestBrazilianStemFilterFactory, UniqFieldsUpdateProcessorFactoryTest, TestJapaneseBaseFormFilterFactory, TestFaceting, DistributedTermsComponentTest, TestJmxIntegration, TestPHPSerializedResponseWriter, FileBasedSpellCheckerTest, TestBulgarianStemFilterFactory, SnowballPorterFilterFactoryTest, TestGermanStemFilterFactory, FullSolrCloudTest, TimeZoneUtilsTest, LukeRequestHandlerTest, SystemInfoHandlerTest, CoreAdminHandlerTest, TestLMJelinekMercerSimilarityFactory, TestGroupingSearch, TestRemoteStreaming, SolrIndexConfigTest, TestItalianLightStemFilterFactory, CSVRequestHandlerTest, FieldMutatingUpdateProcessorTest, TestRussianLightStemFilterFactory, TestPluginEnable, TestJmxMonitoredMap, TestTurkishLowerCaseFilterFactory, TestStemmerOverrideFilterFactory, ConvertedLegacyTest, TestWriterPerf, TestGermanMinimalStemFilterFactory, TestExtendedDismaxParser, TestHyphenationCompoundWordTokenFilterFactory, TestFinnishLightStemFilterFactory, FieldAnalysisRequestHandlerTest, TestIndexingPerformance, FastVectorHighlighterTest, AlternateDirectoryTest, TestPersianNormalizationFilterFactory, BinaryUpdateRequestHandlerTest, CacheHeaderTest, TestCJKBigramFilterFactory, TestDocSet, TestNumberUtils, XmlUpdateRequestHandlerTest, TestRemoveDuplicatesTokenFilterFactory, TestSolrXMLSerializer, TestUpdate, TestBadConfig, MBeansHandlerTest, NoCacheHeaderTest, BasicZkTest, TestPatternReplaceCharFilterFactory, UpdateParamsTest, TestCapitalizationFilterFactory, TestDelimitedPayloadTokenFilterFactory, NotRequiredUniqueKeyTest, SuggesterTest, OverseerTest]
> [junit4] 2>
> [junit4] Completed in 76.66s, 8 tests, 1 failure <<< FAILURES!
> [...truncated 831 lines...]
>
> [...truncated 11934 lines...]
>
> [...truncated 11934 lines...]
>
> [...truncated 11934 lines...]
>
> [...truncated 11934 lines...]
>
> [...truncated 11934 lines...]
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
- Mark Miller
lucidimagination.com
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
[JENKINS] Lucene-Solr-trunk-Windows-Java7-64 - Build # 300 - Still
Failing!
Posted by je...@sd-datasolutions.de.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java7-64/300/
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerTest.testShardLeaderChange
Error Message:
Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
Stack Trace:
org.junit.ComparisonFailure: Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
at __randomizedtesting.SeedInfo.seed([195A5E746C7F55C0:C709D98376E7A031]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.apache.solr.cloud.OverseerTest.verifyShardLeader(OverseerTest.java:522)
at org.apache.solr.cloud.OverseerTest.testShardLeaderChange(OverseerTest.java:677)
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 11002 lines...]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
[junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4] 2>
[junit4] 2> 4792 T1150 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87786223260336139-127.0.0.1:57511_solr-n_0000000008) am no longer a leader.
[junit4] 2> 4937 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
[junit4] 2> 4963 T1153 oasc.Overseer$CloudStateUpdater.run WARNING Overseer cannot talk to ZK
[junit4] 2> 4964 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b000c
[junit4] 2> 4981 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57551 which had sessionid 0x137e11add9b000c
[junit4] 2> 4982 T1127 oaz.ZooKeeper.close Session: 0x137e11add9b000c closed
[junit4] 2> 4982 T1127 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57511/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7d16b6ef
[junit4] 2> 4982 T1152 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 4982 T1154 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57511
[junit4] 2> 4982 T1154 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:57511, initiating session
[junit4] 2> 4982 T1117 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57555
[junit4] 2> 4982 T1117 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57555
[junit4] 2> 5007 T1119 oazs.NIOServerCnxn.finishSessionInit Established session 0x137e11add9b000d with negotiated timeout 10000 for client /127.0.0.1:57555
[junit4] 2> 5008 T1154 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:57511, sessionid = 0x137e11add9b000d, negotiated timeout = 10000
[junit4] 2> 5008 T1155 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d16b6ef name:ZooKeeperConnection Watcher:127.0.0.1:57511/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 5028 T1127 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 5047 T1127 oasc.Overseer.<init> Overseer (id=87786223260336141-127.0.0.1:57511_solr-n_0000000012) starting
[junit4] 2> 5047 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 5060 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 5069 T1120 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e11add9b000d type:create cxid:0xa zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 5091 T1156 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4] 2> 5788 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
[junit4] 2>
[junit4] 2> 6956 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
[junit4] 2> 7895 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
[junit4] 2>
[junit4] 2> 9652 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
[junit4] 2> 10640 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
[junit4] 2>
[junit4] 2> 12479 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
[junit4] 2> 13046 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b000d
[junit4] 2> 13065 T1117 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e11add9b000d, likely client has closed socket
[junit4] 2> 13065 T1127 oaz.ZooKeeper.close Session: 0x137e11add9b000d closed
[junit4] 2> 13065 T1155 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 13066 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57555 which had sessionid 0x137e11add9b000d
[junit4] 2> 13069 T1122 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 13070 T1140 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 13071 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b0008
[junit4] 2> 13073 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57538 which had sessionid 0x137e11add9b0008
[junit4] 2> 13073 T1140 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 13073 T1115 oaz.ZooKeeper.close Session: 0x137e11add9b0008 closed
[junit4] 2> 13074 T1120 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e11add9b0000
[junit4] 2> 13076 T1117 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e11add9b0000, likely client has closed socket
[junit4] 2> 13076 T1115 oaz.ZooKeeper.close Session: 0x137e11add9b0000 closed
[junit4] 2> 13076 T1122 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 13076 T1117 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57514 which had sessionid 0x137e11add9b0000
[junit4] 2> 13079 T1120 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4] 2> 13079 T1119 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4] 2> 13080 T1115 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4] 2> 13171 T1156 oasc.Overseer$CloudStateUpdater.amILeader WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
[junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4] 2>
[junit4] 2> 13171 T1156 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87786223260336141-127.0.0.1:57511_solr-n_0000000012) am no longer a leader.
[junit4] 2> 13422 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x137e118aa7c0010 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused: no further information
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1146)
[junit4] 2>
[junit4] 2> 14960 T1118 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4] 2> 15006 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:57322
[junit4] 2> 15559 T1117 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4] 2> 15561 T1115 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4] 2> 15561 T1115 oas.SolrTestCaseJ4.tearDown ###Ending testShardLeaderChange
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.method=testShardLeaderChange -Dtests.seed=195A5E746C7F55C0 -Dtests.locale=es_CR -Dtests.timezone=Asia/Colombo -Dargs="-Dfile.encoding=Cp1252"
[junit4] 2>
[junit4] > (@AfterClass output)
[junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 76626 T1115 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40: {}, sim=DefaultSimilarity, locale=es_CR, timezone=Asia/Colombo
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 (64-bit)/cpus=2,threads=1,free=113734152,total=259325952
[junit4] 2> NOTE: All tests run in this JVM: [UUIDFieldTest, LengthFilterTest, TestNGramFilters, IndexReaderFactoryTest, TestQueryUtils, OutputWriterTest, SolrRequestParserTest, DistributedQueryElevationComponentTest, MultiTermTest, TestDFRSimilarityFactory, CloudStateUpdateTest, TestCSVLoader, JSONWriterTest, SuggesterTSTTest, TestArbitraryIndexDir, TestRecovery, StatsComponentTest, TestSpanishLightStemFilterFactory, TestPropInject, LeaderElectionTest, TestGermanNormalizationFilterFactory, URLClassifyProcessorTest, TestPseudoReturnFields, TestSolrCoreProperties, PeerSyncTest, DisMaxRequestHandlerTest, TestPortugueseStemFilterFactory, TestDistributedSearch, TestGreekStemFilterFactory, TestGalicianStemFilterFactory, BadIndexSchemaTest, TestShingleFilterFactory, PrimitiveFieldTypeTest, SignatureUpdateProcessorFactoryTest, RequestHandlersTest, TestGalicianMinimalStemFilterFactory, DocumentAnalysisRequestHandlerTest, TestTrie, DirectSolrSpellCheckerTest, LeaderElectionIntegrationTest, TestMappingCharFilterFactory, DistanceFunctionTest, TestBrazilianStemFilterFactory, UniqFieldsUpdateProcessorFactoryTest, TestJapaneseBaseFormFilterFactory, TestFaceting, DistributedTermsComponentTest, TestJmxIntegration, TestPHPSerializedResponseWriter, FileBasedSpellCheckerTest, TestBulgarianStemFilterFactory, SnowballPorterFilterFactoryTest, TestGermanStemFilterFactory, FullSolrCloudTest, TimeZoneUtilsTest, LukeRequestHandlerTest, SystemInfoHandlerTest, CoreAdminHandlerTest, TestLMJelinekMercerSimilarityFactory, TestGroupingSearch, TestRemoteStreaming, SolrIndexConfigTest, TestItalianLightStemFilterFactory, CSVRequestHandlerTest, FieldMutatingUpdateProcessorTest, TestRussianLightStemFilterFactory, TestPluginEnable, TestJmxMonitoredMap, TestTurkishLowerCaseFilterFactory, TestStemmerOverrideFilterFactory, ConvertedLegacyTest, TestWriterPerf, TestGermanMinimalStemFilterFactory, TestExtendedDismaxParser, TestHyphenationCompoundWordTokenFilterFactory, TestFinnishLightStemFilterFactory, FieldAnalysisRequestHandlerTest, TestIndexingPerformance, FastVectorHighlighterTest, AlternateDirectoryTest, TestPersianNormalizationFilterFactory, BinaryUpdateRequestHandlerTest, CacheHeaderTest, TestCJKBigramFilterFactory, TestDocSet, TestNumberUtils, XmlUpdateRequestHandlerTest, TestRemoveDuplicatesTokenFilterFactory, TestSolrXMLSerializer, TestUpdate, TestBadConfig, MBeansHandlerTest, NoCacheHeaderTest, BasicZkTest, TestPatternReplaceCharFilterFactory, UpdateParamsTest, TestCapitalizationFilterFactory, TestDelimitedPayloadTokenFilterFactory, NotRequiredUniqueKeyTest, SuggesterTest, OverseerTest]
[junit4] 2>
[junit4] Completed in 76.66s, 8 tests, 1 failure <<< FAILURES!
[...truncated 831 lines...]
[...truncated 11934 lines...]
[...truncated 11934 lines...]
[...truncated 11934 lines...]
[...truncated 11934 lines...]
[...truncated 11934 lines...]
[JENKINS] Lucene-Solr-trunk-Windows-Java7-64 - Build # 299 - Still
Failing!
Posted by je...@sd-datasolutions.de.
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java7-64/299/
2 tests failed.
REGRESSION: org.apache.solr.cloud.OverseerTest.testShardLeaderChange
Error Message:
Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
Stack Trace:
org.junit.ComparisonFailure: Unexpected shard leader coll:collection1 shard:shard1 expected:<core[4]> but was:<core[1]>
at __randomizedtesting.SeedInfo.seed([742AB9E72396E621:AA793E10390E13D0]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.apache.solr.cloud.OverseerTest.verifyShardLeader(OverseerTest.java:522)
at org.apache.solr.cloud.OverseerTest.testShardLeaderChange(OverseerTest.java:677)
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)
FAILED: org.apache.solr.handler.TestReplicationHandler.test
Error Message:
expected:<498> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<498> but was:<0>
at __randomizedtesting.SeedInfo.seed([742AB9E72396E621:FC7E863D8D6A8BD9]: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.doTestIndexAndConfigReplication(TestReplicationHandler.java:391)
at org.apache.solr.handler.TestReplicationHandler.test(TestReplicationHandler.java:250)
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 11982 lines...]
[junit4] 2> 57741 T1810 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 57745 T1810 oasc.Overseer.<init> Overseer (id=87785560040472611-127.0.0.1:64911_solr-n_0000000014) starting
[junit4] 2> 57746 T1773 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e080729f0023 type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 57747 T1773 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e080729f0023 type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 57753 T1773 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e080729f0023 type:create cxid:0xa zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 57757 T1854 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4] 2> 58067 T1851 oasc.Overseer$CloudStateUpdater.amILeader WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
[junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4] 2>
[junit4] 2> 58067 T1851 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87785560040472610-127.0.0.1:64911_solr-n_0000000012) am no longer a leader.
[junit4] 2> 59557 T1773 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e080729f0023
[junit4] 2> 59558 T1770 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65019 which had sessionid 0x137e080729f0023
[junit4] 2> 59558 T1810 oaz.ZooKeeper.close Session: 0x137e080729f0023 closed
[junit4] 2> 59559 T1810 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:64911/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@304feace
[junit4] 2> 59559 T1853 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 59561 T1855 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:64911
[junit4] 2> 59561 T1770 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65022
[junit4] 2> 59561 T1855 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:64911, initiating session
[junit4] 2> 59563 T1770 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65022
[junit4] 2> 59565 T1772 oazs.NIOServerCnxn.finishSessionInit Established session 0x137e080729f0024 with negotiated timeout 10000 for client /127.0.0.1:65022
[junit4] 2> 59565 T1855 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:64911, sessionid = 0x137e080729f0024, negotiated timeout = 10000
[junit4] 2> 59565 T1856 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@304feace name:ZooKeeperConnection Watcher:127.0.0.1:64911/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 59568 T1810 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 59572 T1810 oasc.Overseer.<init> Overseer (id=87785560040472612-127.0.0.1:64911_solr-n_0000000016) starting
[junit4] 2> 59573 T1773 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e080729f0024 type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 59574 T1773 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e080729f0024 type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 59580 T1773 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137e080729f0024 type:create cxid:0xa zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 59584 T1857 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4] 2> 59702 T1773 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e080729f0024
[junit4] 2> 59705 T1810 oaz.ZooKeeper.close Session: 0x137e080729f0024 closed
[junit4] 2> 59705 T1856 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 59705 T1770 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65022 which had sessionid 0x137e080729f0024
[junit4] 2> 59707 T1839 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 59707 T1775 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 59708 T1773 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e080729f001e
[junit4] 2> 59709 T1770 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e080729f001e, likely client has closed socket
[junit4] 2> 59709 T1273 oaz.ZooKeeper.close Session: 0x137e080729f001e closed
[junit4] 2> 59709 T1770 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65004 which had sessionid 0x137e080729f001e
[junit4] 2> 59709 T1839 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 59710 T1773 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137e080729f0000
[junit4] 2> 59713 T1770 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x137e080729f0000, likely client has closed socket
[junit4] 2> 59713 T1273 oaz.ZooKeeper.close Session: 0x137e080729f0000 closed
[junit4] 2> 59713 T1775 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 59713 T1770 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:64914 which had sessionid 0x137e080729f0000
[junit4] 2> 59714 T1773 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4] 2> 59715 T1772 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4] 2> 59715 T1273 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4] 2> 60147 T1854 oasc.Overseer$CloudStateUpdater.amILeader WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
[junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4] 2>
[junit4] 2> 60148 T1854 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87785560040472611-127.0.0.1:64911_solr-n_0000000014) am no longer a leader.
[junit4] 2> 60181 T1857 oasc.Overseer$CloudStateUpdater.amILeader WARNING org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:186)
[junit4] 2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:111)
[junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4] 2>
[junit4] 2> 60181 T1857 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87785560040472612-127.0.0.1:64911_solr-n_0000000016) am no longer a leader.
[junit4] 2> 61957 T1771 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4] 2> 62223 T1770 oazs.NIOServerCnxn$Factory.run WARNING Ignoring exception java.nio.channels.ClosedChannelException
[junit4] 2> at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:242)
[junit4] 2>
[junit4] 2> 62223 T1770 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4] 2> 62225 T1273 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4] 2> 62225 T1273 oas.SolrTestCaseJ4.tearDown ###Ending testShardLeaderChange
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.method=testShardLeaderChange -Dtests.seed=742AB9E72396E621 -Dtests.locale=sv_SE -Dtests.timezone=CST -Dargs="-Dfile.encoding=Cp1252"
[junit4] 2>
[junit4] > (@AfterClass output)
[junit4] 2> 79372 T1273 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 79372 T1273 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 79372 T1273 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40: {}, sim=RandomSimilarityProvider(queryNorm=false,coord=true): {}, locale=sv_SE, timezone=CST
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 (64-bit)/cpus=2,threads=2,free=168782640,total=285016064
[junit4] 2> NOTE: All tests run in this JVM: [DisMaxRequestHandlerTest, CommonGramsQueryFilterFactoryTest, TestPseudoReturnFields, CommonGramsFilterFactoryTest, TestSurroundQueryParser, IndexSchemaRuntimeFieldTest, TestPatternTokenizerFactory, SpatialFilterTest, TestGalicianMinimalStemFilterFactory, CopyFieldTest, LukeRequestHandlerTest, TestBadConfig, DocumentBuilderTest, TestQueryUtils, FileBasedSpellCheckerTest, ResourceLoaderTest, TestJmxMonitoredMap, TestConfig, StatsComponentTest, SystemInfoHandlerTest, DirectSolrConnectionTest, OutputWriterTest, TestSolrDeletionPolicy2, SoftAutoCommitTest, DistributedSpellCheckComponentTest, TestPortugueseStemFilterFactory, TestCapitalizationFilterFactory, TestItalianLightStemFilterFactory, TestDistributedSearch, CoreAdminHandlerTest, TestThaiWordFilterFactory, TestFrenchLightStemFilterFactory, CloudStateTest, UniqFieldsUpdateProcessorFactoryTest, TestGalicianStemFilterFactory, SpellingQueryConverterTest, TestRemoteStreaming, TestHyphenationCompoundWordTokenFilterFactory, SolrRequestParserTest, TestMappingCharFilterFactory, TermVectorComponentTest, TestJapaneseTokenizerFactory, TestPersianNormalizationFilterFactory, LoggingHandlerTest, TestLuceneMatchVersion, TestShingleFilterFactory, AutoCommitTest, SampleTest, TestSolrXMLSerializer, TestDelimitedPayloadTokenFilterFactory, TestNorwegianMinimalStemFilterFactory, IndexBasedSpellCheckerTest, CurrencyFieldTest, SearchHandlerTest, TestWordDelimiterFilterFactory, MultiTermTest, TestFastLRUCache, TestSystemIdResolver, TestPluginEnable, LengthFilterTest, TestFunctionQuery, ConvertedLegacyTest, TestSolrCoreProperties, TestDocSet, TestWikipediaTokenizerFactory, TestGermanNormalizationFilterFactory, TestPhraseSuggestions, TestJmxIntegration, TestTrimFilterFactory, TestIndonesianStemFilterFactory, RequestHandlersTest, TestTypeTokenFilterFactory, TestJapanesePartOfSpeechStopFilterFactory, DistanceFunctionTest, MBeansHandlerTest, TimeZoneUtilsTest, TestIndexSearcher, XsltUpdateRequestHandlerTest, TestTrie, TestEnglishMinimalStemFilterFactory, TestHungarianLightStemFilterFactory, TestGreekLowerCaseFilterFactory, TestFoldingMultitermQuery, SolrCoreCheckLockOnStartupTest, TestReverseStringFilterFactory, BinaryUpdateRequestHandlerTest, DoubleMetaphoneFilterFactoryTest, BasicZkTest, TestXIncludeConfig, TestCzechStemFilterFactory, DefaultValueUpdateProcessorTest, TestSwedishLightStemFilterFactory, TestHindiFilters, TestHashPartitioner, TestPorterStemFilterFactory, SpellCheckComponentTest, TestDFRSimilarityFactory, CloudStateUpdateTest, TestPatternReplaceCharFilterFactory, TestHTMLStripCharFilterFactory, SolrCmdDistributorTest, URLClassifyProcessorTest, CacheHeaderTest, TestRealTimeGet, BadComponentTest, TestRangeQuery, TestCSVResponseWriter, ZkControllerTest, TestReversedWildcardFilterFactory, TestKeywordMarkerFilterFactory, TestKeepFilterFactory, TestFiltering, SolrInfoMBeanTest, CSVRequestHandlerTest, TestRemoveDuplicatesTokenFilterFactory, SignatureUpdateProcessorFactoryTest, JSONWriterTest, MoreLikeThisHandlerTest, TestIndexingPerformance, UUIDFieldTest, TestTurkishLowerCaseFilterFactory, PingRequestHandlerTest, TestNumberUtils, RecoveryZkTest, DirectUpdateHandlerOptimizeTest, TestMergePolicyConfig, TestPerFieldSimilarity, ReturnFieldsTest, TestRussianLightStemFilterFactory, FastVectorHighlighterTest, TestGermanMinimalStemFilterFactory, SolrIndexConfigTest, ZkNodePropsTest, TestJapaneseBaseFormFilterFactory, TestIrishLowerCaseFilterFactory, TestLFUCache, IndexReaderFactoryTest, TestBM25SimilarityFactory, SnowballPorterFilterFactoryTest, PrimitiveFieldTypeTest, TestDistributedGrouping, TestCJKBigramFilterFactory, DocumentAnalysisRequestHandlerTest, TestPortugueseLightStemFilterFactory, TestPHPSerializedResponseWriter, TestStemmerOverrideFilterFactory, FieldMutatingUpdateProcessorTest, TestFinnishLightStemFilterFactory, NoCacheHeaderTest, EchoParamsTest, FieldAnalysisRequestHandlerTest, TestNGramFilters, TestBrazilianStemFilterFactory, NotRequiredUniqueKeyTest, FullSolrCloudDistribCmdsTest, DateFieldTest, TestPatternReplaceFilterFactory, DateMathParserTest, BasicFunctionalityTest, TestLatvianStemFilterFactory, UpdateParamsTest, OverseerTest]
[junit4] 2>
[junit4] Completed in 79.39s, 8 tests, 1 failure <<< FAILURES!
[...truncated 3657 lines...]
[junit4] 2> 43629 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 43633 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_nrm.cfe} status=0 QTime=0
[junit4] 2> 43638 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdx} status=0 QTime=0
[junit4] 2> 43648 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_nrm.cfs} status=0 QTime=0
[junit4] 2> 43655 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tip} status=0 QTime=0
[junit4] 2> 43665 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=segments_7} status=0 QTime=0
[junit4] 2> 43670 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tim} status=0 QTime=0
[junit4] 2> 43677 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.frq} status=0 QTime=0
[junit4] 2> 43685 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.si} status=0 QTime=0
[junit4] 2> 43690 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fnm} status=0 QTime=0
[junit4] 2> 43698 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdt} status=0 QTime=0
[junit4] 2> 43700 T2357 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 43703 T2357 oash.SnapPuller.copyAFile SEVERE Unable to move index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064549\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512\_3_nrm.cfe Trying to do a copy
[junit4] 2> 43704 T2357 oash.SnapPuller.copyAFile SEVERE The index directory does not exist: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512 dirs found: [index.20120612064549, index.properties, replication.properties]
[junit4] 2> 43704 T2357 oash.SnapPuller.copyAFile SEVERE Unable to copy index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064549\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512\_3_nrm.cfe java.io.FileNotFoundException: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512\_3_nrm.cfe (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:703)
[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> 43765 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 43895 T2162 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:65175
[junit4] 2> 43903 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 44041 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 44179 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 44316 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 44455 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1
[junit4] 2> 44594 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 44733 T2349 C144 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0
[junit4] 2> 44754 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin} status=0 QTime=0
[junit4] 2> 44755 T2357 oash.SnapPuller.fetchLatestIndex Master's generation: 7
[junit4] 2> 44755 T2357 oash.SnapPuller.fetchLatestIndex Slave's generation: 6
[junit4] 2> 44755 T2357 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 44759 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filelist&wt=javabin&generation=7} status=0 QTime=1
[junit4] 2> 44759 T2357 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 44765 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_nrm.cfe} status=0 QTime=0
[junit4] 2> 44771 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdx} status=0 QTime=0
[junit4] 2> 44778 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_nrm.cfs} status=0 QTime=0
[junit4] 2> 44790 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tip} status=0 QTime=0
[junit4] 2> 44803 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=segments_7} status=0 QTime=0
[junit4] 2> 44809 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.tim} status=0 QTime=0
[junit4] 2> 44816 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3_Lucene40_0.frq} status=0 QTime=1
[junit4] 2> 44828 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.si} status=0 QTime=0
[junit4] 2> 44852 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fnm} status=0 QTime=0
[junit4] 2> 44858 T2340 C143 REQ [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&generation=7&wt=filestream&file=_3.fdt} status=0 QTime=0
[junit4] 2> 44861 T2357 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 44865 T2357 oash.SnapPuller.copyAFile SEVERE Unable to move index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064550\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512\_3_nrm.cfe Trying to do a copy
[junit4] 2> 44865 T2357 oash.SnapPuller.copyAFile SEVERE The index directory does not exist: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512 dirs found: [index.20120612064550, index.properties, replication.properties]
[junit4] 2> 44865 T2357 oash.SnapPuller.copyAFile SEVERE Unable to copy index file from: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064550\_3_nrm.cfe to: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512\_3_nrm.cfe java.io.FileNotFoundException: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java7-64\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1339501505805\slave\data\index.20120612064512\_3_nrm.cfe (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:703)
[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> 44868 T2291 oas.SolrTestCaseJ4.tearDown ###Ending test
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.method=test -Dtests.seed=742AB9E72396E621 -Dtests.locale=sr_ME_#Latn -Dtests.timezone=CST6CDT -Dargs="-Dfile.encoding=Cp1252"
[junit4] 2>
[junit4] > (@AfterClass output)
[junit4] 2> 44889 T2291 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=287715871
[junit4] 2> 44891 T2291 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@668ebdf9
[junit4] 2> 44893 T2291 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 44896 T2291 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=997,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=0}
[junit4] 2> 44928 T2291 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 45106 T2291 oejut.QueuedThreadPool.doStop WARNING 6 threads could not be stopped
[junit4] 2> 45109 T2291 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3761757
[junit4] 2> 45109 T2291 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7bbc8afc
[junit4] 2> 45109 T2291 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 45113 T2291 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=8,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 45114 T2291 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 45192 T2291 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Appending, sim=RandomSimilarityProvider(queryNorm=true,coord=false): {}, locale=sr_ME_#Latn, timezone=CST6CDT
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_04 (64-bit)/cpus=2,threads=1,free=174147808,total=275644416
[junit4] 2> NOTE: All tests run in this JVM: [DisMaxRequestHandlerTest, CommonGramsQueryFilterFactoryTest, TestPseudoReturnFields, CommonGramsFilterFactoryTest, TestSurroundQueryParser, IndexSchemaRuntimeFieldTest, TestPatternTokenizerFactory, SpatialFilterTest, TestGalicianMinimalStemFilterFactory, CopyFieldTest, LukeRequestHandlerTest, TestBadConfig, DocumentBuilderTest, TestQueryUtils, FileBasedSpellCheckerTest, ResourceLoaderTest, TestJmxMonitoredMap, TestConfig, StatsComponentTest, SystemInfoHandlerTest, DirectSolrConnectionTest, OutputWriterTest, TestSolrDeletionPolicy2, SoftAutoCommitTest, DistributedSpellCheckComponentTest, TestPortugueseStemFilterFactory, TestCapitalizationFilterFactory, TestItalianLightStemFilterFactory, TestDistributedSearch, CoreAdminHandlerTest, TestThaiWordFilterFactory, TestFrenchLightStemFilterFactory, CloudStateTest, UniqFieldsUpdateProcessorFactoryTest, TestGalicianStemFilterFactory, SpellingQueryConverterTest, TestRemoteStreaming, TestHyphenationCompoundWordTokenFilterFactory, SolrRequestParserTest, TestMappingCharFilterFactory, TermVectorComponentTest, TestJapaneseTokenizerFactory, TestPersianNormalizationFilterFactory, LoggingHandlerTest, TestLuceneMatchVersion, TestShingleFilterFactory, AutoCommitTest, SampleTest, TestSolrXMLSerializer, TestDelimitedPayloadTokenFilterFactory, TestNorwegianMinimalStemFilterFactory, IndexBasedSpellCheckerTest, CurrencyFieldTest, SearchHandlerTest, TestWordDelimiterFilterFactory, MultiTermTest, TestFastLRUCache, TestSystemIdResolver, TestPluginEnable, LengthFilterTest, TestFunctionQuery, ConvertedLegacyTest, TestSolrCoreProperties, TestDocSet, TestWikipediaTokenizerFactory, TestGermanNormalizationFilterFactory, TestPhraseSuggestions, TestJmxIntegration, TestTrimFilterFactory, TestIndonesianStemFilterFactory, RequestHandlersTest, TestTypeTokenFilterFactory, TestJapanesePartOfSpeechStopFilterFactory, DistanceFunctionTest, MBeansHandlerTest, TimeZoneUtilsTest, TestIndexSearcher, XsltUpdateRequestHandlerTest, TestTrie, TestEnglishMinimalStemFilterFactory, TestHungarianLightStemFilterFactory, TestGreekLowerCaseFilterFactory, TestFoldingMultitermQuery, SolrCoreCheckLockOnStartupTest, TestReverseStringFilterFactory, BinaryUpdateRequestHandlerTest, DoubleMetaphoneFilterFactoryTest, BasicZkTest, TestXIncludeConfig, TestCzechStemFilterFactory, DefaultValueUpdateProcessorTest, TestSwedishLightStemFilterFactory, TestHindiFilters, TestHashPartitioner, TestPorterStemFilterFactory, SpellCheckComponentTest, TestDFRSimilarityFactory, CloudStateUpdateTest, TestPatternReplaceCharFilterFactory, TestHTMLStripCharFilterFactory, SolrCmdDistributorTest, URLClassifyProcessorTest, CacheHeaderTest, TestRealTimeGet, BadComponentTest, TestRangeQuery, TestCSVResponseWriter, ZkControllerTest, TestReversedWildcardFilterFactory, TestKeywordMarkerFilterFactory, TestKeepFilterFactory, TestFiltering, SolrInfoMBeanTest, CSVRequestHandlerTest, TestRemoveDuplicatesTokenFilterFactory, SignatureUpdateProcessorFactoryTest, JSONWriterTest, MoreLikeThisHandlerTest, TestIndexingPerformance, UUIDFieldTest, TestTurkishLowerCaseFilterFactory, PingRequestHandlerTest, TestNumberUtils, RecoveryZkTest, DirectUpdateHandlerOptimizeTest, TestMergePolicyConfig, TestPerFieldSimilarity, ReturnFieldsTest, TestRussianLightStemFilterFactory, FastVectorHighlighterTest, TestGermanMinimalStemFilterFactory, SolrIndexConfigTest, ZkNodePropsTest, TestJapaneseBaseFormFilterFactory, TestIrishLowerCaseFilterFactory, TestLFUCache, IndexReaderFactoryTest, TestBM25SimilarityFactory, SnowballPorterFilterFactoryTest, PrimitiveFieldTypeTest, TestDistributedGrouping, TestCJKBigramFilterFactory, DocumentAnalysisRequestHandlerTest, TestPortugueseLightStemFilterFactory, TestPHPSerializedResponseWriter, TestStemmerOverrideFilterFactory, FieldMutatingUpdateProcessorTest, TestFinnishLightStemFilterFactory, NoCacheHeaderTest, EchoParamsTest, FieldAnalysisRequestHandlerTest, TestNGramFilters, TestBrazilianStemFilterFactory, NotRequiredUniqueKeyTest, FullSolrCloudDistribCmdsTest, DateFieldTest, TestPatternReplaceFilterFactory, DateMathParserTest, BasicFunctionalityTest, TestLatvianStemFilterFactory, UpdateParamsTest, OverseerTest, HighlighterTest, TestBeiderMorseFilterFactory, TestLRUCache, FullSolrCloudTest, SuggesterTSTTest, UpdateRequestProcessorFactoryTest, RAMDirectoryFactoryTest, HighlighterConfigTest, SolrPluginUtilsTest, BadIndexSchemaTest, TestValueSourceCache, TestArbitraryIndexDir, TestJapaneseKatakanaStemFilterFactory, RequiredFieldsTest, TestBinaryField, TestDefaultSimilarityFactory, TestGermanStemFilterFactory, SpellCheckCollatorTest, DirectSolrSpellCheckerTest, TestStopFilterFactory, TestCJKWidthFilterFactory, PolyFieldTest, TestCoreContainer, IndexSchemaTest, QueryElevationComponentTest, TestUpdate, TestExtendedDismaxParser, SpellPossibilityIteratorTest, TestDictionaryCompoundWordTokenFilterFactory, TermsComponentTest, TestQuerySenderListener, PeerSyncTest, TestReplicationHandler]
[junit4] 2>
[junit4] Completed in 45.20s, 1 test, 1 failure <<< FAILURES!
[...truncated 251 lines...]
[...truncated 16092 lines...]
[...truncated 16092 lines...]
[...truncated 16092 lines...]
[...truncated 16092 lines...]
[...truncated 16092 lines...]