You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2012/06/09 05:00:58 UTC
[JENKINS] Lucene-Solr-tests-only-trunk - Build # 14643 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-tests-only-trunk/14643/
1 tests failed.
REGRESSION: org.apache.solr.cloud.OverseerTest.testShardLeaderChange
Error Message:
Unexpected shard leader coll:collection1 shard:shard1 expected:<core[1]> but was:<core[4]>
Stack Trace:
org.junit.ComparisonFailure: Unexpected shard leader coll:collection1 shard:shard1 expected:<core[1]> but was:<core[4]>
at __randomizedtesting.SeedInfo.seed([E1CD79FC4836B0F7:3F9EFE0B52AE4506]: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:673)
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:616)
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 11149 lines...]
[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:679)
[junit4] 2>
[junit4] 2> 17700 T1489 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87766509155713060-127.0.0.1:40338_solr-n_0000000010) am no longer a leader.
[junit4] 2> 19638 T1408 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137cf2cd1670025
[junit4] 2> 19645 T1491 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 19645 T1451 oaz.ZooKeeper.close Session: 0x137cf2cd1670025 closed
[junit4] 2> 19645 T1405 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:40376 which had sessionid 0x137cf2cd1670025
[junit4] 2> 19645 T1451 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40338/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@716f733f
[junit4] 2> 19646 T1493 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:40338
[junit4] 2> 19646 T1493 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:40338, initiating session
[junit4] 2> 19646 T1405 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:40377
[junit4] 2> 19646 T1405 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:40377
[junit4] 2> 19647 T1407 oazs.NIOServerCnxn.finishSessionInit Established session 0x137cf2cd1670026 with negotiated timeout 10000 for client /140.211.11.196:40377
[junit4] 2> 19647 T1493 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:40338, sessionid = 0x137cf2cd1670026, negotiated timeout = 10000
[junit4] 2> 19647 T1494 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@716f733f name:ZooKeeperConnection Watcher:127.0.0.1:40338/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 19660 T1451 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 19661 T1451 oasc.Overseer.<init> Overseer (id=87766509155713062-127.0.0.1:40338_solr-n_0000000014) starting
[junit4] 2> 19661 T1408 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137cf2cd1670026 type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 19662 T1408 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137cf2cd1670026 type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 19670 T1495 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4] 2> 19821 T1492 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:679)
[junit4] 2>
[junit4] 2> 19821 T1492 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87766509155713061-127.0.0.1:40338_solr-n_0000000012) am no longer a leader.
[junit4] 2> 23609 T1408 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137cf2cd1670026
[junit4] 2> 23610 T1494 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 23610 T1451 oaz.ZooKeeper.close Session: 0x137cf2cd1670026 closed
[junit4] 2> 23611 T1405 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:40377 which had sessionid 0x137cf2cd1670026
[junit4] 2> 23611 T1451 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40338/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@288485e9
[junit4] 2> 23611 T1496 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:40338
[junit4] 2> 23612 T1496 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:40338, initiating session
[junit4] 2> 23612 T1405 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:40378
[junit4] 2> 23612 T1405 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:40378
[junit4] 2> 23613 T1407 oazs.NIOServerCnxn.finishSessionInit Established session 0x137cf2cd1670027 with negotiated timeout 10000 for client /140.211.11.196:40378
[junit4] 2> 23613 T1496 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:40338, sessionid = 0x137cf2cd1670027, negotiated timeout = 10000
[junit4] 2> 23613 T1497 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@288485e9 name:ZooKeeperConnection Watcher:127.0.0.1:40338/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 23625 T1451 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 23627 T1451 oasc.Overseer.<init> Overseer (id=87766509155713063-127.0.0.1:40338_solr-n_0000000016) starting
[junit4] 2> 23628 T1408 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137cf2cd1670027 type:create cxid:0x8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 23629 T1408 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x137cf2cd1670027 type:create cxid:0x9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 23639 T1498 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4] 2> 23679 T1495 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:679)
[junit4] 2>
[junit4] 2> 23679 T1495 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=87766509155713062-127.0.0.1:40338_solr-n_0000000014) am no longer a leader.
[junit4] 2> 24244 T1408 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137cf2cd1670027
[junit4] 2> 24245 T1497 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 24245 T1451 oaz.ZooKeeper.close Session: 0x137cf2cd1670027 closed
[junit4] 2> 24245 T1405 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:40378 which had sessionid 0x137cf2cd1670027
[junit4] 2> 24247 T1481 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 24247 T1483 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 24247 T1410 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 24247 T1408 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137cf2cd1670021
[junit4] 2> 24258 T1405 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:40372 which had sessionid 0x137cf2cd1670021
[junit4] 2> 24258 T1285 oaz.ZooKeeper.close Session: 0x137cf2cd1670021 closed
[junit4] 2> 24260 T1483 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 24260 T1410 oascc.ZkStateReader$3.process Updating live nodes
[junit4] 2> 24260 T1408 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137cf2cd1670022
[junit4] 2> 24267 T1285 oaz.ZooKeeper.close Session: 0x137cf2cd1670022 closed
[junit4] 2> 24267 T1405 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:40373 which had sessionid 0x137cf2cd1670022
[junit4] 2> 24267 T1408 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x137cf2cd1670000
[junit4] 2> 24268 T1410 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4] 2> 24268 T1405 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:40339 which had sessionid 0x137cf2cd1670000
[junit4] 2> 24268 T1285 oaz.ZooKeeper.close Session: 0x137cf2cd1670000 closed
[junit4] 2> 24268 T1408 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4] 2> 24268 T1407 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4] 2> 24268 T1285 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4] 2> 24538 T1405 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4] 2> 24538 T1285 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4] 2> 24538 T1285 oas.SolrTestCaseJ4.tearDown ###Ending testShardLeaderChange
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.method=testShardLeaderChange -Dtests.seed=E1CD79FC4836B0F7 -Dtests.multiplier=3 -Dtests.locale=ko_KR -Dtests.timezone=America/North_Dakota/New_Salem -Dargs="-Dfile.encoding=ISO8859-1"
[junit4] 2>
[junit4] > (@AfterClass output)
[junit4] 2> 35268 T1285 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 35268 T1285 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 35268 T1285 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40: {}, sim=RandomSimilarityProvider(queryNorm=true,coord=false): {}, locale=ko_KR, timezone=America/North_Dakota/New_Salem
[junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=5,free=91817032,total=275775488
[junit4] 2> NOTE: All tests run in this JVM: [TestPatternTokenizerFactory, FieldMutatingUpdateProcessorTest, TestPHPSerializedResponseWriter, TestMultiWordSynonyms, TestUtils, URLClassifyProcessorTest, TestKeepFilterFactory, TestRealTimeGet, TestBeiderMorseFilterFactory, TestQueryTypes, TestPseudoReturnFields, TestKStemFilterFactory, DoubleMetaphoneFilterFactoryTest, TestGalicianMinimalStemFilterFactory, TestSynonymFilterFactory, IndexBasedSpellCheckerTest, TestPhoneticFilterFactory, TestArbitraryIndexDir, DocumentBuilderTest, TestGroupingSearch, TestFastLRUCache, SnowballPorterFilterFactoryTest, DistributedTermsComponentTest, UpdateRequestProcessorFactoryTest, UpdateParamsTest, TestFaceting, TestNumberUtils, SolrPluginUtilsTest, TestDistributedSearch, TestNGramFilters, LukeRequestHandlerTest, BasicDistributedZkTest, TestGreekStemFilterFactory, SpatialFilterTest, TestArabicFilters, TestPortugueseMinimalStemFilterFactory, FileUtilsTest, TestElisionFilterFactory, TestDelimitedPayloadTokenFilterFactory, TestGermanLightStemFilterFactory, AutoCommitTest, TestBrazilianStemFilterFactory, NoCacheHeaderTest, TestJapaneseBaseFormFilterFactory, RequiredFieldsTest, ReturnFieldsTest, TestJapaneseKatakanaStemFilterFactory, SuggesterFSTTest, SuggesterWFSTTest, TestGermanStemFilterFactory, RecoveryZkTest, TestNorwegianMinimalStemFilterFactory, TestTrimFilterFactory, DirectUpdateHandlerOptimizeTest, SolrInfoMBeanTest, SpellCheckComponentTest, TestWriterPerf, TestSpanishLightStemFilterFactory, RAMDirectoryFactoryTest, XsltUpdateRequestHandlerTest, TestBulgarianStemFilterFactory, TestLuceneMatchVersion, TestHindiFilters, TestFrenchMinimalStemFilterFactory, TestHyphenationCompoundWordTokenFilterFactory, TestPorterStemFilterFactory, TestHTMLStripCharFilterFactory, TestEnglishMinimalStemFilterFactory, TestHungarianLightStemFilterFactory, TestUpdate, DocumentAnalysisRequestHandlerTest, SpellCheckCollatorTest, SuggesterTSTTest, TestSwedishLightStemFilterFactory, TestHunspellStemFilterFactory, FieldAnalysisRequestHandlerTest, StandardRequestHandlerTest, TestMappingCharFilterFactory, TestPluginEnable, TestGalicianStemFilterFactory, PeerSyncTest, FileBasedSpellCheckerTest, TestSuggestSpellingConverter, TestDFRSimilarityFactory, OverseerTest]
[junit4] 2>
[junit4] Completed on J1 in 35.33s, 8 tests, 1 failure <<< FAILURES!
[...truncated 88 lines...]
[...truncated 11338 lines...]
[...truncated 11338 lines...]
[...truncated 11338 lines...]
[...truncated 11338 lines...]
[...truncated 11338 lines...]