Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java8/17/
1 tests failed. FAILED: org.apache.solr.cloud.CdcrRequestHandlerTest.doTest Error Message: expected:<st[art]ed> but was:<st[opp]ed> Stack Trace: org.junit.ComparisonFailure: expected:<st[art]ed> but was:<st[opp]ed> at __randomizedtesting.SeedInfo.seed([480F43E26A71FAD7:EF4BFB4607CAE96E]:0) at org.junit.Assert.assertEquals(Assert.java:125) at org.junit.Assert.assertEquals(Assert.java:147) at org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertState(BaseCdcrDistributedZkTest.java:255) at org.apache.solr.cloud.CdcrRequestHandlerTest.doTestLifeCycleActions(CdcrRequestHandlerTest.java:55) at org.apache.solr.cloud.CdcrRequestHandlerTest.doTest(CdcrRequestHandlerTest.java:39) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9898 lines...] [junit4] Suite: org.apache.solr.cloud.CdcrRequestHandlerTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/init-core-data-001 [junit4] 2> 622708 T2651 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 622708 T2651 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 622713 T2651 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 2> 622731 T2652 oasc.ZkTestServer$2$1.setClientPort client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 622732 T2652 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 622831 T2651 oasc.ZkTestServer.run start zk server on port:39084 [junit4] 2> 622832 T2651 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 622843 T2651 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 622854 T2659 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19db9d08 name:ZooKeeperConnection Watcher:127.0.0.1:39084 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 622855 T2651 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 622855 T2651 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 622855 T2651 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 622858 T2651 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 622875 T2651 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 622897 T2662 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b7fc143 name:ZooKeeperConnection Watcher:127.0.0.1:39084/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 622897 T2651 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 622898 T2651 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 622898 T2651 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 622901 T2651 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 622902 T2651 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 622903 T2651 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 622905 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/solrconfig-cdcr.xml to /configs/conf1/solrconfig.xml [junit4] 2> 622905 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 622907 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 622907 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 622909 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 622909 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 622910 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 622911 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 622912 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 622913 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 622914 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 622914 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 622915 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 622916 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 622917 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 622917 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 622918 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 622919 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 622920 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 622920 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 622921 T2651 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 622922 T2651 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 622928 T2651 oas.SolrTestCaseJ4.setUp ###Starting doTest [junit4] 2> 623583 T2651 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1 [junit4] 2> 623589 T2651 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 623671 T2651 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@5180208b{/,null,AVAILABLE} [junit4] 2> 623672 T2651 oejs.AbstractConnector.doStart Started ServerConnector@32d0a7d4{HTTP/1.1}{127.0.0.1:35700} [junit4] 2> 623673 T2651 oejs.Server.doStart Started @625648ms [junit4] 2> 623673 T2651 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {shards=shard1, hostContext=/, hostPort=35700, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores} [junit4] 2> 623673 T2651 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 623674 T2651 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/' [junit4] 2> 623691 T2651 oasc.SolrXmlConfig.fromFile Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/solr.xml [junit4] 2> 623699 T2651 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores [junit4] 2> 623699 T2651 oasc.CoreContainer.<init> New CoreContainer 250487759 [junit4] 2> 623700 T2651 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/] [junit4] 2> 623700 T2651 oasc.CoreContainer.load loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/lib [junit4] 2> 623700 T2651 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/lib). [junit4] 2> 623710 T2651 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 623711 T2651 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 623712 T2651 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 623712 T2651 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 623713 T2651 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 623713 T2651 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:39084/solr [junit4] 2> 623713 T2651 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 623713 T2651 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 623719 T2651 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 623723 T2676 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7033396a name:ZooKeeperConnection Watcher:127.0.0.1:39084 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623723 T2651 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 623724 T2651 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 623726 T2651 n:127.0.0.1:35700_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 623727 T2679 n:127.0.0.1:35700_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@568c7c1f name:ZooKeeperConnection Watcher:127.0.0.1:39084/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623727 T2651 n:127.0.0.1:35700_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 623729 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 623731 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 623744 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 623746 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 623747 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 623749 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 623750 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 623751 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 623752 T2651 n:127.0.0.1:35700_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35700_ [junit4] 2> 623753 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35700_ [junit4] 2> 623759 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 623760 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 623761 T2651 n:127.0.0.1:35700_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 623770 T2651 n:127.0.0.1:35700_ oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:35700_ [junit4] 2> 623771 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 623774 T2651 n:127.0.0.1:35700_ oasc.Overseer.start Overseer (id=93893228149211139-127.0.0.1:35700_-n_0000000000) starting [junit4] 2> 623779 T2651 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 623791 T2651 n:127.0.0.1:35700_ oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 623793 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 623796 T2651 n:127.0.0.1:35700_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 623797 T2681 n:127.0.0.1:35700_ oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 623802 T2651 n:127.0.0.1:35700_ oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 623804 T2651 n:127.0.0.1:35700_ oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 623805 T2651 n:127.0.0.1:35700_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores [junit4] 2> 623805 T2651 n:127.0.0.1:35700_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1, collection=tmp_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 623806 T2651 n:127.0.0.1:35700_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/ [junit4] 2> 623806 T2651 n:127.0.0.1:35700_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 623814 T2683 n:127.0.0.1:35700_ c:tmp_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=tmp_collection [junit4] 2> 623814 T2683 n:127.0.0.1:35700_ c:tmp_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 623815 T2679 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 623816 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35700", [junit4] 2> "node_name":"127.0.0.1:35700_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 623817 T2683 n:127.0.0.1:35700_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 623817 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35700", [junit4] 2> "node_name":"127.0.0.1:35700_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 623817 T2680 n:127.0.0.1:35700_ oasco.ClusterStateMutator.createCollection building a new cName: tmp_collection [junit4] 2> 623817 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 623819 T2679 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 624817 T2683 n:127.0.0.1:35700_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 624817 T2683 n:127.0.0.1:35700_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:tmp_collection [junit4] 2> 624818 T2683 n:127.0.0.1:35700_ oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:tmp_collection [junit4] 2> 624818 T2683 n:127.0.0.1:35700_ oasc.ZkController.getConfName Looking for collection configName [junit4] 2> 624819 T2683 n:127.0.0.1:35700_ oasc.ZkController.getConfName Only one config set found in zk - using it:conf1 [junit4] 2> 624819 T2683 n:127.0.0.1:35700_ oascc.SolrZkClient.makePath makePath: /collections/tmp_collection [junit4] 2> 624820 T2683 n:127.0.0.1:35700_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/tmp_collection [junit4] 2> 624820 T2683 n:127.0.0.1:35700_ oascc.ZkStateReader.readConfigName path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 624820 T2683 n:127.0.0.1:35700_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/' [junit4] 2> 624834 T2683 n:127.0.0.1:35700_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 624838 T2683 n:127.0.0.1:35700_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 624842 T2683 n:127.0.0.1:35700_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 624849 T2683 n:127.0.0.1:35700_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 624850 T2683 n:127.0.0.1:35700_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 624854 T2683 n:127.0.0.1:35700_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 624936 T2683 n:127.0.0.1:35700_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 624937 T2683 n:127.0.0.1:35700_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 624938 T2683 n:127.0.0.1:35700_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 624941 T2683 n:127.0.0.1:35700_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 624955 T2683 n:127.0.0.1:35700_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection tmp_collection [junit4] 2> 624955 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 624956 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/], dataDir=[null] [junit4] 2> 624956 T2683 n:127.0.0.1:35700_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@54c3cae [junit4] 2> 624957 T2683 n:127.0.0.1:35700_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/data [junit4] 2> 624957 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/data/index/ [junit4] 2> 624957 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 624958 T2683 n:127.0.0.1:35700_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-001/cores/collection1/data/index [junit4] 2> 624958 T2683 n:127.0.0.1:35700_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=29.32421875, floorSegmentMB=0.5615234375, forceMergeDeletesPctAllowed=28.49751237319183, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1671167471663667 [junit4] 2> 624959 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6c5b76ed lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44b1b3b1),segFN=segments_1,generation=1} [junit4] 2> 624959 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 624963 T2683 n:127.0.0.1:35700_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "cdcr-processor-chain" [junit4] 2> 624979 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 624981 T2683 n:127.0.0.1:35700_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 624982 T2683 n:127.0.0.1:35700_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 624983 T2683 n:127.0.0.1:35700_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 624984 T2683 n:127.0.0.1:35700_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 624985 T2683 n:127.0.0.1:35700_ x:collection1 oasc.PluginBag.createPlugin requestHandler : '/replication' created with startup=lazy [junit4] 2> 625002 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 625006 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 625006 T2683 n:127.0.0.1:35700_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/cdcr,/update,/admin/file [junit4] 2> 625007 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 625020 T2683 n:127.0.0.1:35700_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 625020 T2683 n:127.0.0.1:35700_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 625021 T2683 n:127.0.0.1:35700_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 625021 T2683 n:127.0.0.1:35700_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 625021 T2683 n:127.0.0.1:35700_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=6.107421875, floorSegmentMB=0.2041015625, forceMergeDeletesPctAllowed=19.3890113393149, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 625022 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6c5b76ed lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44b1b3b1),segFN=segments_1,generation=1} [junit4] 2> 625022 T2683 n:127.0.0.1:35700_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 625022 T2683 n:127.0.0.1:35700_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@19387c0b[collection1] main [junit4] 2> 625023 T2683 n:127.0.0.1:35700_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/tmp_collection [junit4] 2> 625024 T2683 n:127.0.0.1:35700_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 625024 T2683 n:127.0.0.1:35700_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 625025 T2683 n:127.0.0.1:35700_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 625025 T2683 n:127.0.0.1:35700_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 625025 T2683 n:127.0.0.1:35700_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 625025 T2683 n:127.0.0.1:35700_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 625026 T2683 n:127.0.0.1:35700_ x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 625026 T2683 n:127.0.0.1:35700_ x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 625073 T2683 n:127.0.0.1:35700_ x:collection1 oascc.SolrZkClient.makePath makePath: /collections/tmp_collection/cdcr/state [junit4] 2> 625076 T2683 n:127.0.0.1:35700_ x:collection1 oash.CdcrBufferStateManager.createStateNode Created znode /collections/tmp_collection/cdcr/state/buffer [junit4] 2> 625081 T2683 n:127.0.0.1:35700_ x:collection1 oash.CdcrProcessStateManager.createStateNode Created znode /collections/tmp_collection/cdcr/state/process [junit4] 2> 625102 T2683 n:127.0.0.1:35700_ x:collection1 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/" [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore [junit4] 2> 625102 T2683 n:127.0.0.1:35700_ x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 625102 T2684 n:127.0.0.1:35700_ x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19387c0b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 625103 T2683 n:127.0.0.1:35700_ x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 625103 T2683 n:127.0.0.1:35700_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 625104 T2683 n:127.0.0.1:35700_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 625104 T2683 n:127.0.0.1:35700_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in index or recent updates, using new clock 1502291652866211840 [junit4] 2> 625106 T2683 n:127.0.0.1:35700_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 3 ms to seed version buckets with highest version 1502291652866211840 [junit4] 2> 625106 T2683 n:127.0.0.1:35700_ x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 625107 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:35700 collection:tmp_collection shard:shard2 [junit4] 2> 625107 T2651 n:127.0.0.1:35700_ oass.SolrDispatchFilter.init user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2 [junit4] 2> 625108 T2651 n:127.0.0.1:35700_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 625112 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/tmp_collection/leader_elect/shard2/election [junit4] 2> 625119 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 625120 T2679 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 625120 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 625120 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C635 name=collection1 org.apache.solr.core.SolrCore@617e1328 url=http://127.0.0.1:35700/collection1 node=127.0.0.1:35700_ C635_STATE=coll:tmp_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:35700, node_name=127.0.0.1:35700_, state=down} [junit4] 2> 625120 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 C635 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:35700/collection1/ [junit4] 2> 625121 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection"} current state version: 1 [junit4] 2> 625121 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 C635 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 625123 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 C635 oasc.SyncStrategy.syncToMe http://127.0.0.1:35700/collection1/ has no replicas [junit4] 2> 625123 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:35700/collection1/ shard2 [junit4] 2> 625123 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/tmp_collection/leaders/shard2 [junit4] 2> 625125 T2679 n:127.0.0.1:35700_ oash.CdcrLeaderStateManager$LeaderStateWatcher.process Received new leader state @ tmp_collection:shard2 [junit4] 2> 625167 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 625168 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "base_url":"http://127.0.0.1:35700", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 625271 T2689 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 625280 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ZkController.register We are http://127.0.0.1:35700/collection1/ and leader is http://127.0.0.1:35700/collection1/ [junit4] 2> 625280 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35700 [junit4] 2> 625281 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 625281 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=tmp_collection [junit4] 2> 625281 T2688 n:127.0.0.1:35700_ c:tmp_collection s:shard2 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 625282 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 625283 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35700", [junit4] 2> "node_name":"127.0.0.1:35700_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 625283 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35700", [junit4] 2> "node_name":"127.0.0.1:35700_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 625386 T2689 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 625455 T2651 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1 [junit4] 2> 625457 T2651 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 625461 T2651 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@2e6f4451{/,null,AVAILABLE} [junit4] 2> 625462 T2651 oejs.AbstractConnector.doStart Started ServerConnector@5683be27{HTTP/1.1}{127.0.0.1:55865} [junit4] 2> 625462 T2651 oejs.Server.doStart Started @627438ms [junit4] 2> 625462 T2651 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {shards=shard2, hostContext=/, hostPort=55865, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores} [junit4] 2> 625463 T2651 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 625463 T2651 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/' [junit4] 2> 625477 T2651 oasc.SolrXmlConfig.fromFile Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/solr.xml [junit4] 2> 625482 T2651 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores [junit4] 2> 625482 T2651 oasc.CoreContainer.<init> New CoreContainer 739249349 [junit4] 2> 625482 T2651 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/] [junit4] 2> 625483 T2651 oasc.CoreContainer.load loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/lib [junit4] 2> 625483 T2651 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/lib). [junit4] 2> 625490 T2651 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 625492 T2651 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 625492 T2651 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 625493 T2651 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 625493 T2651 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 625493 T2651 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:39084/solr [junit4] 2> 625493 T2651 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 625494 T2651 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 625494 T2651 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 625496 T2703 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1461b9ac name:ZooKeeperConnection Watcher:127.0.0.1:39084 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 625496 T2651 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 625497 T2651 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 625499 T2651 n:127.0.0.1:55865_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 625500 T2706 n:127.0.0.1:55865_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3cbe25b3 name:ZooKeeperConnection Watcher:127.0.0.1:39084/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 625500 T2651 n:127.0.0.1:55865_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 625504 T2651 n:127.0.0.1:55865_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 626507 T2651 n:127.0.0.1:55865_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55865_ [junit4] 2> 626509 T2651 n:127.0.0.1:55865_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55865_ [junit4] 2> 626513 T2651 n:127.0.0.1:55865_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 626514 T2651 n:127.0.0.1:55865_ oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 626515 T2651 n:127.0.0.1:55865_ oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 626515 T2651 n:127.0.0.1:55865_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores [junit4] 2> 626516 T2651 n:127.0.0.1:55865_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1, collection=tmp_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 626516 T2651 n:127.0.0.1:55865_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/ [junit4] 2> 626517 T2651 n:127.0.0.1:55865_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 626518 T2707 n:127.0.0.1:55865_ c:tmp_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=tmp_collection [junit4] 2> 626518 T2707 n:127.0.0.1:55865_ c:tmp_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 626519 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 626520 T2707 n:127.0.0.1:55865_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 626520 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55865", [junit4] 2> "node_name":"127.0.0.1:55865_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 626524 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55865", [junit4] 2> "node_name":"127.0.0.1:55865_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 626524 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 626524 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 626628 T2706 n:127.0.0.1:55865_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 626628 T2689 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 627524 T2707 n:127.0.0.1:55865_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 627524 T2707 n:127.0.0.1:55865_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:tmp_collection [junit4] 2> 627525 T2707 n:127.0.0.1:55865_ oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 627525 T2707 n:127.0.0.1:55865_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/tmp_collection [junit4] 2> 627525 T2707 n:127.0.0.1:55865_ oascc.ZkStateReader.readConfigName path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 627526 T2707 n:127.0.0.1:55865_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/' [junit4] 2> 627541 T2707 n:127.0.0.1:55865_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 627547 T2707 n:127.0.0.1:55865_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 627550 T2707 n:127.0.0.1:55865_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 627559 T2707 n:127.0.0.1:55865_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 627560 T2707 n:127.0.0.1:55865_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 627564 T2707 n:127.0.0.1:55865_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 627666 T2707 n:127.0.0.1:55865_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 627667 T2707 n:127.0.0.1:55865_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 627667 T2707 n:127.0.0.1:55865_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 627671 T2707 n:127.0.0.1:55865_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 627690 T2707 n:127.0.0.1:55865_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection tmp_collection [junit4] 2> 627690 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 627690 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/], dataDir=[null] [junit4] 2> 627690 T2707 n:127.0.0.1:55865_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@54c3cae [junit4] 2> 627691 T2707 n:127.0.0.1:55865_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/data [junit4] 2> 627691 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/data/index/ [junit4] 2> 627691 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 627692 T2707 n:127.0.0.1:55865_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-002/cores/collection1/data/index [junit4] 2> 627692 T2707 n:127.0.0.1:55865_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=29.32421875, floorSegmentMB=0.5615234375, forceMergeDeletesPctAllowed=28.49751237319183, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1671167471663667 [junit4] 2> 627694 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@41fe6696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43ca4753),segFN=segments_1,generation=1} [junit4] 2> 627694 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 627699 T2707 n:127.0.0.1:55865_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "cdcr-processor-chain" [junit4] 2> 627699 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 627701 T2707 n:127.0.0.1:55865_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627702 T2707 n:127.0.0.1:55865_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627703 T2707 n:127.0.0.1:55865_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627705 T2707 n:127.0.0.1:55865_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627706 T2707 n:127.0.0.1:55865_ x:collection1 oasc.PluginBag.createPlugin requestHandler : '/replication' created with startup=lazy [junit4] 2> 627709 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 627710 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 627710 T2707 n:127.0.0.1:55865_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/cdcr,/update,/admin/file [junit4] 2> 627711 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 627711 T2707 n:127.0.0.1:55865_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 627711 T2707 n:127.0.0.1:55865_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 627712 T2707 n:127.0.0.1:55865_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 627713 T2707 n:127.0.0.1:55865_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 627714 T2707 n:127.0.0.1:55865_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=6.107421875, floorSegmentMB=0.2041015625, forceMergeDeletesPctAllowed=19.3890113393149, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 627714 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@41fe6696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43ca4753),segFN=segments_1,generation=1} [junit4] 2> 627714 T2707 n:127.0.0.1:55865_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 627715 T2707 n:127.0.0.1:55865_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@37dd25d3[collection1] main [junit4] 2> 627715 T2707 n:127.0.0.1:55865_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/tmp_collection [junit4] 2> 627716 T2707 n:127.0.0.1:55865_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 627716 T2707 n:127.0.0.1:55865_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 627717 T2707 n:127.0.0.1:55865_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 627717 T2707 n:127.0.0.1:55865_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 627717 T2707 n:127.0.0.1:55865_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 627717 T2707 n:127.0.0.1:55865_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 627718 T2707 n:127.0.0.1:55865_ x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 627718 T2707 n:127.0.0.1:55865_ x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 627724 T2707 n:127.0.0.1:55865_ x:collection1 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/" [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore [junit4] 2> 627725 T2707 n:127.0.0.1:55865_ x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 627725 T2708 n:127.0.0.1:55865_ x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37dd25d3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 627726 T2707 n:127.0.0.1:55865_ x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 627726 T2707 n:127.0.0.1:55865_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 627726 T2707 n:127.0.0.1:55865_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 627726 T2707 n:127.0.0.1:55865_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in index or recent updates, using new clock 1502291655615578112 [junit4] 2> 627728 T2707 n:127.0.0.1:55865_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 2 ms to seed version buckets with highest version 1502291655615578112 [junit4] 2> 627729 T2707 n:127.0.0.1:55865_ x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 627729 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55865 collection:tmp_collection shard:shard1 [junit4] 2> 627730 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/tmp_collection/leader_elect/shard1/election [junit4] 2> 627733 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 627734 T2651 n:127.0.0.1:55865_ oass.SolrDispatchFilter.init user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2 [junit4] 2> 627734 T2651 n:127.0.0.1:55865_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 627734 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 627735 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 627735 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection"} current state version: 4 [junit4] 2> 627738 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C636 name=collection1 org.apache.solr.core.SolrCore@320baade url=http://127.0.0.1:55865/collection1 node=127.0.0.1:55865_ C636_STATE=coll:tmp_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:55865, node_name=127.0.0.1:55865_, state=down} [junit4] 2> 627738 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 C636 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55865/collection1/ [junit4] 2> 627739 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 C636 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 627739 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 C636 oasc.SyncStrategy.syncToMe http://127.0.0.1:55865/collection1/ has no replicas [junit4] 2> 627739 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55865/collection1/ shard1 [junit4] 2> 627739 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/tmp_collection/leaders/shard1 [junit4] 2> 627741 T2706 n:127.0.0.1:55865_ oash.CdcrLeaderStateManager$LeaderStateWatcher.process Received new leader state @ tmp_collection:shard1 [junit4] 2> 627756 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 627757 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "base_url":"http://127.0.0.1:55865", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 627859 T2706 n:127.0.0.1:55865_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 627859 T2689 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 627908 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ZkController.register We are http://127.0.0.1:55865/collection1/ and leader is http://127.0.0.1:55865/collection1/ [junit4] 2> 627908 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55865 [junit4] 2> 627908 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 627908 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=tmp_collection [junit4] 2> 627909 T2712 n:127.0.0.1:55865_ c:tmp_collection s:shard1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 627911 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 627912 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55865", [junit4] 2> "node_name":"127.0.0.1:55865_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 627912 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55865", [junit4] 2> "node_name":"127.0.0.1:55865_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 628014 T2706 n:127.0.0.1:55865_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 628014 T2689 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 628124 T2651 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1 [junit4] 2> 628125 T2651 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 628147 T2651 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@31d2f845{/,null,AVAILABLE} [junit4] 2> 628148 T2651 oejs.AbstractConnector.doStart Started ServerConnector@4cecd9e{HTTP/1.1}{127.0.0.1:60257} [junit4] 2> 628148 T2651 oejs.Server.doStart Started @630124ms [junit4] 2> 628148 T2651 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {shards=shard3, hostContext=/, hostPort=60257, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores} [junit4] 2> 628149 T2651 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 628149 T2651 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/' [junit4] 2> 628172 T2651 oasc.SolrXmlConfig.fromFile Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/solr.xml [junit4] 2> 628179 T2651 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores [junit4] 2> 628179 T2651 oasc.CoreContainer.<init> New CoreContainer 1254529275 [junit4] 2> 628179 T2651 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/] [junit4] 2> 628180 T2651 oasc.CoreContainer.load loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/lib [junit4] 2> 628180 T2651 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/lib). [junit4] 2> 628190 T2651 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 628192 T2651 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 628192 T2651 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 628193 T2651 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 628193 T2651 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 628193 T2651 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:39084/solr [junit4] 2> 628193 T2651 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 628194 T2651 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 628194 T2651 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 628198 T2726 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73193b8 name:ZooKeeperConnection Watcher:127.0.0.1:39084 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 628198 T2651 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 628199 T2651 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 628206 T2651 n:127.0.0.1:60257_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 628207 T2729 n:127.0.0.1:60257_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fad32bf name:ZooKeeperConnection Watcher:127.0.0.1:39084/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 628207 T2651 n:127.0.0.1:60257_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 628210 T2651 n:127.0.0.1:60257_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 629213 T2651 n:127.0.0.1:60257_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60257_ [junit4] 2> 629214 T2651 n:127.0.0.1:60257_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60257_ [junit4] 2> 629217 T2651 n:127.0.0.1:60257_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 629219 T2651 n:127.0.0.1:60257_ oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 629219 T2651 n:127.0.0.1:60257_ oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 629220 T2651 n:127.0.0.1:60257_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores [junit4] 2> 629221 T2651 n:127.0.0.1:60257_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1, collection=tmp_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 629222 T2651 n:127.0.0.1:60257_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/ [junit4] 2> 629222 T2651 n:127.0.0.1:60257_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 629224 T2730 n:127.0.0.1:60257_ c:tmp_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=tmp_collection [junit4] 2> 629229 T2730 n:127.0.0.1:60257_ c:tmp_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 629230 T2689 n:127.0.0.1:35700_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 629230 T2680 n:127.0.0.1:35700_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60257", [junit4] 2> "node_name":"127.0.0.1:60257_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 629231 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60257", [junit4] 2> "node_name":"127.0.0.1:60257_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 629231 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 629231 T2680 n:127.0.0.1:35700_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 629231 T2730 n:127.0.0.1:60257_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 629335 T2706 n:127.0.0.1:55865_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 629335 T2689 n:127.0.0.1:35700_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 629335 T2729 n:127.0.0.1:60257_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 630232 T2730 n:127.0.0.1:60257_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 630232 T2730 n:127.0.0.1:60257_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:tmp_collection [junit4] 2> 630232 T2730 n:127.0.0.1:60257_ oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 630233 T2730 n:127.0.0.1:60257_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/tmp_collection [junit4] 2> 630233 T2730 n:127.0.0.1:60257_ oascc.ZkStateReader.readConfigName path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 630233 T2730 n:127.0.0.1:60257_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/' [junit4] 2> 630248 T2730 n:127.0.0.1:60257_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 630253 T2730 n:127.0.0.1:60257_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 630256 T2730 n:127.0.0.1:60257_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 630286 T2730 n:127.0.0.1:60257_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 630288 T2730 n:127.0.0.1:60257_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 630292 T2730 n:127.0.0.1:60257_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 630362 T2730 n:127.0.0.1:60257_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 630363 T2730 n:127.0.0.1:60257_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 630364 T2730 n:127.0.0.1:60257_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 630366 T2730 n:127.0.0.1:60257_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 630379 T2730 n:127.0.0.1:60257_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection tmp_collection [junit4] 2> 630379 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 630379 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/], dataDir=[null] [junit4] 2> 630379 T2730 n:127.0.0.1:60257_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@54c3cae [junit4] 2> 630380 T2730 n:127.0.0.1:60257_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/data [junit4] 2> 630381 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/data/index/ [junit4] 2> 630381 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 630381 T2730 n:127.0.0.1:60257_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-003/cores/collection1/data/index [junit4] 2> 630381 T2730 n:127.0.0.1:60257_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=29.32421875, floorSegmentMB=0.5615234375, forceMergeDeletesPctAllowed=28.49751237319183, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1671167471663667 [junit4] 2> 630382 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@bbf875f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e0c56e),segFN=segments_1,generation=1} [junit4] 2> 630382 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 630386 T2730 n:127.0.0.1:60257_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "cdcr-processor-chain" [junit4] 2> 630386 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 630388 T2730 n:127.0.0.1:60257_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630389 T2730 n:127.0.0.1:60257_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630390 T2730 n:127.0.0.1:60257_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630391 T2730 n:127.0.0.1:60257_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630392 T2730 n:127.0.0.1:60257_ x:collection1 oasc.PluginBag.createPlugin requestHandler : '/replication' created with startup=lazy [junit4] 2> 630395 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 630395 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 630396 T2730 n:127.0.0.1:60257_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/cdcr,/update,/admin/file [junit4] 2> 630396 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 630397 T2730 n:127.0.0.1:60257_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 630397 T2730 n:127.0.0.1:60257_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 630398 T2730 n:127.0.0.1:60257_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 630398 T2730 n:127.0.0.1:60257_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 630405 T2730 n:127.0.0.1:60257_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=6.107421875, floorSegmentMB=0.2041015625, forceMergeDeletesPctAllowed=19.3890113393149, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 630405 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@bbf875f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e0c56e),segFN=segments_1,generation=1} [junit4] 2> 630406 T2730 n:127.0.0.1:60257_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 630406 T2730 n:127.0.0.1:60257_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@6e179aad[collection1] main [junit4] 2> 630406 T2730 n:127.0.0.1:60257_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/tmp_collection [junit4] 2> 630407 T2730 n:127.0.0.1:60257_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 630407 T2730 n:127.0.0.1:60257_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 630408 T2730 n:127.0.0.1:60257_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 630408 T2730 n:127.0.0.1:60257_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 630408 T2730 n:127.0.0.1:60257_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 630409 T2730 n:127.0.0.1:60257_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 630409 T2730 n:127.0.0.1:60257_ x:collection1 oasr.Man [...truncated too long message...] ype:NodeDataChanged path:/collections/source_collection/state.json for collection source_collection has occurred - updating... (live nodes size: 1) [junit4] 2> 651186 T2754 n:127.0.0.1:53337_ oascc.ZkStateReader.updateWatchedCollection Updating data for source_collection to ver 16 [junit4] 2> 651188 T2754 n:127.0.0.1:53337_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 651190 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 651190 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.CachingDirectoryFactory.closeCacheValue looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-004/cores/source_collection_shard2_replica1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-004/cores/source_collection_shard2_replica1/data/index;done=false>>] [junit4] 2> 651191 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.CachingDirectoryFactory.close Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-004/cores/source_collection_shard2_replica1/data/index [junit4] 2> 651191 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.CachingDirectoryFactory.closeCacheValue looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-004/cores/source_collection_shard2_replica1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-004/cores/source_collection_shard2_replica1/data;done=false>>] [junit4] 2> 651191 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.CachingDirectoryFactory.close Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001/jetty-004/cores/source_collection_shard2_replica1/data [junit4] 2> 651192 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.Overseer.close Overseer (id=93893228149211145-127.0.0.1:53337_-n_0000000003) closing [junit4] 2> 651193 T2841 n:127.0.0.1:53337_ oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:53337_ [junit4] 2> 652699 T2754 n:127.0.0.1:53337_ oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 652701 T2651 oejs.AbstractConnector.doStop Stopped ServerConnector@1f444a62{HTTP/1.1}{127.0.0.1:0} [junit4] 2> 652702 T2651 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@7b903a88{/,null,UNAVAILABLE} [junit4] 2> 652702 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oas.SolrTestCaseJ4.tearDown ###Ending doTest [junit4] 2> 652704 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:39084 39084 [junit4] 2> 652925 T2652 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:39084 39084 [junit4] 2> 652927 T2652 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 10 /solr/aliases.json [junit4] 2> 10 /solr/clusterstate.json [junit4] 2> 8 /solr/configs/conf1 [junit4] 2> 4 /solr/collections/source_collection/state.json [junit4] 2> 2 /solr/collections/tmp_collection/leaders/shard1 [junit4] 2> 2 /solr/collections/tmp_collection/leaders/shard2 [junit4] 2> 2 /solr/collections/source_collection/leaders/shard1 [junit4] 2> 2 /solr/collections/source_collection/leaders/shard2 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 5 /solr/collections/source_collection/cdcr/state/process [junit4] 2> 4 /solr/collections/source_collection/cdcr/state/buffer [junit4] 2> 4 /solr/collections/tmp_collection/cdcr/state/buffer [junit4] 2> 4 /solr/collections/tmp_collection/cdcr/state/process [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 10 /solr/live_nodes [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CdcrRequestHandlerTest -Dtests.method=doTest -Dtests.seed=480F43E26A71FAD7 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Europe/Samara -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 30.2s J2 | CdcrRequestHandlerTest.doTest <<< [junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<st[art]ed> but was:<st[opp]ed> [junit4] > at __randomizedtesting.SeedInfo.seed([480F43E26A71FAD7:EF4BFB4607CAE96E]:0) [junit4] > at org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertState(BaseCdcrDistributedZkTest.java:255) [junit4] > at org.apache.solr.cloud.CdcrRequestHandlerTest.doTestLifeCycleActions(CdcrRequestHandlerTest.java:55) [junit4] > at org.apache.solr.cloud.CdcrRequestHandlerTest.doTest(CdcrRequestHandlerTest.java:39) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 652939 T2651 c:source_collection s:shard1 x:source_collection_shard1_replica1 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrRequestHandlerTest 480F43E26A71FAD7-001 [junit4] 2> 30234 T2650 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=zh, timezone=Europe/Samara [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=1,free=77685152,total=526385152 [junit4] 2> NOTE: All tests run in this JVM: [HdfsSyncSliceTest, SpellCheckCollatorTest, AtomicUpdatesTest, TestSolr4Spatial2, MergeStrategyTest, TestSolrQueryParser, TestSearcherReuse, SolrTestCaseJ4Test, TestDefaultSimilarityFactory, TestGroupingSearch, TestNoOpRegenerator, HdfsUnloadDistributedZkTest, DocValuesTest, DistributedQueryElevationComponentTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestReload, TestRecovery, DistanceUnitsTest, CachingDirectoryFactoryTest, SolrIndexSplitterTest, SampleTest, TestRestoreCore, TestNRTOpen, HardAutoCommitTest, TestSolrXml, TestSchemaSimilarityResource, TestInitQParser, TestStressReorder, DirectUpdateHandlerOptimizeTest, BasicDistributedZk2Test, BufferStoreTest, CursorPagingTest, PolyFieldTest, OverriddenZkACLAndCredentialsProvidersTest, HdfsRecoverLeaseTest, TestSchemaManager, ShardSplitTest, LeaderElectionTest, SuggesterTest, AliasIntegrationTest, TermVectorComponentDistributedTest, SynonymTokenizerTest, TestExtendedDismaxParser, TestFastOutputStream, ChangedSchemaMergeTest, TestAddFieldRealTimeGet, DistributedFacetPivotWhiteBoxTest, LoggingHandlerTest, TestStressRecovery, TestExactSharedStatsCache, TestLeaderElectionZkExpiry, AsyncMigrateRouteKeyTest, NotRequiredUniqueKeyTest, CdcrRequestHandlerTest] [junit4] Completed [180/503] on J2 in 30.50s, 1 test, 1 failure <<< FAILURES! [...truncated 992 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:526: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/common-build.xml:512: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/common-build.xml:1415: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/common-build.xml:973: There were test failures: 503 suites, 1985 tests, 1 failure, 35 ignored (25 assumptions) Total time: 51 minutes 32 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-Tests-trunk-Java8 #16 Archived 4 artifacts Archive block size is 32768 Received 0 blocks and 290029160 bytes Compression is 0.0% Took 45 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org