Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1979/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.cloud.DeleteReplicaTest.deleteLiveReplicaTest Error Message: Should have had a good message here Stack Trace: java.lang.AssertionError: Should have had a good message here at __randomizedtesting.SeedInfo.seed([BAEC14A249145254:178CA0A9542BFA21]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.DeleteReplicaTest.deleteLiveReplicaTest(DeleteReplicaTest.java:125) 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:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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 9068 lines...] [junit4] Suite: org.apache.solr.cloud.DeleteReplicaTest [junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout. [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/init-core-data-001 [junit4] 2> 2776 T11 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 2898 T11 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /eq_t/ [junit4] 2> 2996 T11 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3007 T12 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3307 T11 oasc.ZkTestServer.run start zk server on port:49203 [junit4] 2> 3491 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3690 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4003 T19 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3744e664 name:ZooKeeperConnection Watcher:127.0.0.1:49203 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4005 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4035 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4049 T11 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4186 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4193 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4196 T22 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22e4d12d name:ZooKeeperConnection Watcher:127.0.0.1:49203/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4197 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4198 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4248 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4264 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4278 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4311 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4323 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 4374 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4389 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 4391 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4402 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4404 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4415 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 4417 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4428 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 4430 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4442 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 4445 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4458 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 4460 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4475 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 4477 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4488 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4492 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4504 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 4505 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4515 T11 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 4518 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 7357 T11 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/cores/collection1 [junit4] 2> 7663 T11 oejul.Log.initialized Logging initialized @11784ms [junit4] 2> 8404 T11 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 8681 T11 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@66bf90bd{/eq_t,null,AVAILABLE} [junit4] 2> 8712 T11 oejs.AbstractConnector.doStart Started ServerConnector@c1f21b0{HTTP/1.1}{127.0.0.1:49206} [junit4] 2> 8719 T11 oejs.Server.doStart Started @12862ms [junit4] 2> 8823 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 8877 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 8878 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001 [junit4] 2> 8879 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/' [junit4] 2> 9579 T11 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/solr.xml [junit4] 2> 10015 T11 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/cores [junit4] 2> 10048 T11 oasc.CoreContainer.<init> New CoreContainer 1232516451 [junit4] 2> 10050 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/] [junit4] 2> 10217 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 10219 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 10235 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 10236 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 10237 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 10238 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 10239 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 10240 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 10240 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 10241 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 10242 T11 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 10766 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 10783 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 10787 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 10794 T11 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 10794 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49203/solr [junit4] 2> 10841 T11 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 10842 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 10845 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 10863 T37 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f800e7c name:ZooKeeperConnection Watcher:127.0.0.1:49203 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 10865 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 10866 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 10892 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 10896 T40 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a619c25 name:ZooKeeperConnection Watcher:127.0.0.1:49203/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 10897 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 10923 T11 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 10944 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 10964 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 10977 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 10988 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 11018 T11 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 11025 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49206_eq_t [junit4] 2> 11034 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49206_eq_t [junit4] 2> 11049 T11 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 11057 T11 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 11065 T11 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 11095 T11 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:49206_eq_t [junit4] 2> 11095 T11 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 11102 T11 oasc.Overseer.start Overseer (id=93274686620172291-127.0.0.1:49206_eq_t-n_0000000000) starting [junit4] 2> 11125 T11 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 11263 T11 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 11682 T42 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 11686 T11 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 11704 T11 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 11712 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 11792 T41 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 12052 T11 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/cores [junit4] 2> 12216 T11 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/cores/collection1/ [junit4] 2> 12216 T11 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 12225 T44 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 12227 T44 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 12250 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 12250 T44 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 12270 T41 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49206/eq_t", [junit4] 2> "node_name":"127.0.0.1:49206_eq_t", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 12311 T41 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49206/eq_t", [junit4] 2> "node_name":"127.0.0.1:49206_eq_t", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 12315 T41 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 12348 T41 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 12366 T40 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> 13254 T44 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 13255 T44 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 13256 T44 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 13257 T44 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 13262 T44 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 13267 T44 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/cores/collection1/' [junit4] 2> 13339 T44 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 13431 T44 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 13570 T44 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 13658 T44 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 13898 T44 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 13947 T44 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 14158 T44 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 15623 T44 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 15672 T44 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 15674 T44 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 16509 T44 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 16524 T44 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 16533 T44 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 16545 T44 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 16545 T44 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 16546 T44 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 16550 T44 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 16550 T44 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 16568 T44 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 16749 T44 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 16772 T44 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/control/data/ [junit4] 2> 16782 T44 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@35e122cc [junit4] 2> 17027 T44 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/control/data [junit4] 2> 17028 T44 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/control/data/index/ [junit4] 2> 17029 T44 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 17076 T44 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/control/data/index [junit4] 2> 17082 T44 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 17341 T44 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@75c3f778 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e10b0a9),segFN=segments_1,generation=1} [junit4] 2> 17343 T44 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 18329 T44 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 18438 T44 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 18455 T44 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 18455 T44 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 18456 T44 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 18456 T44 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 18572 T44 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 18573 T44 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 18573 T44 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 18656 T44 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 18658 T44 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 18659 T44 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 18661 T44 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 18664 T44 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 18668 T44 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 18703 T44 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 18710 T44 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 18713 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 18718 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 18720 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 18721 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 18723 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 18724 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 18725 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 18766 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 18769 T44 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 18804 T44 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 19003 T44 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 19029 T44 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 19032 T44 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 19034 T44 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 19037 T44 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 19161 T44 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 19168 T44 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 19172 T44 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5918691378609128] [junit4] 2> 19185 T44 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@75c3f778 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e10b0a9),segFN=segments_1,generation=1} [junit4] 2> 19185 T44 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 19297 T44 oass.SolrIndexSearcher.<init> Opening Searcher@7e8c94d3[collection1] main [junit4] 2> 19341 T44 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 19345 T44 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 19346 T44 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 19368 T44 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 19369 T44 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 19517 T44 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 19519 T44 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 19520 T44 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 19520 T44 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 19734 T44 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 19757 T44 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 19767 T45 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e8c94d3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 19769 T48 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49206/eq_t collection:control_collection shard:shard1 [junit4] 2> 19792 T11 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 19798 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 19847 T48 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 19906 T48 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 19922 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 19926 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 19934 T51 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4879ba6 name:ZooKeeperConnection Watcher:127.0.0.1:49203/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 19936 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 19937 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 19939 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 19942 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 19944 T48 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 19945 T48 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 19948 T41 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@1b6cd423 url=http://127.0.0.1:49206/eq_t/collection1 node=127.0.0.1:49206_eq_t C0_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:49206/eq_t, node_name=127.0.0.1:49206_eq_t, state=down} [junit4] 2> 19970 T48 C0 P49206 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49206/eq_t/collection1/ [junit4] 2> 19980 T48 C0 P49206 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 19983 T48 C0 P49206 oasc.SyncStrategy.syncToMe http://127.0.0.1:49206/eq_t/collection1/ has no replicas [junit4] 2> 19992 T40 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> 19991 T51 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 0) [junit4] 2> 19993 T48 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49206/eq_t/collection1/ shard1 [junit4] 2> 20013 T48 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 20064 T11 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 20064 T11 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 20065 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 20078 T54 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@560a05cb name:ZooKeeperConnection Watcher:127.0.0.1:49203/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 20090 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 20091 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 20093 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 20133 T55 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 20170 T41 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 20171 T41 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 20196 T41 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:49206/eq_t", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 20203 T41 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 20218 T51 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> 20247 T55 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 20357 T51 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> 20436 T48 oasc.ZkController.register We are http://127.0.0.1:49206/eq_t/collection1/ and leader is http://127.0.0.1:49206/eq_t/collection1/ [junit4] 2> 20444 T48 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49206/eq_t [junit4] 2> 20444 T48 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 20445 T48 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 20445 T48 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 20463 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 20521 T41 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:49206/eq_t", [junit4] 2> "node_name":"127.0.0.1:49206_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 20522 T41 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:49206/eq_t", [junit4] 2> "node_name":"127.0.0.1:49206_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 20669 T51 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> 20670 T40 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> 22293 T11 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/cores/collection1 [junit4] 2> 22305 T11 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 22309 T11 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 22316 T11 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@3029c72d{/eq_t,null,AVAILABLE} [junit4] 2> 22318 T11 oejs.AbstractConnector.doStart Started ServerConnector@6e83ca70{HTTP/1.1}{127.0.0.1:49211} [junit4] 2> 22319 T11 oejs.Server.doStart Started @26462ms [junit4] 2> 22319 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 22320 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 22320 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001 [junit4] 2> 22321 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/' [junit4] 2> 22378 T11 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/solr.xml [junit4] 2> 22396 T11 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/cores [junit4] 2> 22398 T11 oasc.CoreContainer.<init> New CoreContainer 53882794 [junit4] 2> 22399 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/] [junit4] 2> 22400 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 22400 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 22400 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 22401 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 22402 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 22402 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 22403 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 22403 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 22404 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 22404 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 22404 T11 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 22405 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 22406 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 22406 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 22407 T11 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 22407 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49203/solr [junit4] 2> 22407 T11 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 22408 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 22423 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 22430 T69 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a8adefd name:ZooKeeperConnection Watcher:127.0.0.1:49203 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 22431 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 22431 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 22439 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 22445 T72 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fd4041 name:ZooKeeperConnection Watcher:127.0.0.1:49203/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 22445 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 22473 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 23495 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49211_eq_t [junit4] 2> 23499 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49211_eq_t [junit4] 2> 23518 T11 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 23531 T11 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/cores [junit4] 2> 23533 T11 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/cores/collection1/ [junit4] 2> 23534 T11 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 23543 T73 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 23543 T73 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 23552 T73 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 23553 T73 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 23554 T73 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 23559 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 23562 T41 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:49211/eq_t", [junit4] 2> "node_name":"127.0.0.1:49211_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 23563 T41 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:49211/eq_t", [junit4] 2> "node_name":"127.0.0.1:49211_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 23563 T41 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 23563 T41 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 23580 T41 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 23582 T73 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 23583 T73 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 23584 T72 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 23586 T72 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 24584 T73 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 24585 T73 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 24589 T73 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 24590 T73 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 24598 T73 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 24599 T73 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/cores/collection1/' [junit4] 2> 24627 T73 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 24633 T73 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 24709 T73 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 24722 T73 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 24747 T73 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 24752 T73 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 24875 T73 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 25138 T73 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 25170 T73 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 25174 T73 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 25247 T73 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 25268 T73 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 25278 T73 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 25285 T73 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 25286 T73 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 25288 T73 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 25293 T73 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 25293 T73 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 25294 T73 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 25294 T73 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 25295 T73 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty1/ [junit4] 2> 25296 T73 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@35e122cc [junit4] 2> 25301 T73 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty1 [junit4] 2> 25302 T73 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty1/index/ [junit4] 2> 25302 T73 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 25303 T73 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty1/index [junit4] 2> 25303 T73 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 25305 T73 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3772eb31 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4155dc9d),segFN=segments_1,generation=1} [junit4] 2> 25306 T73 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 25323 T73 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 25324 T73 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 25325 T73 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 25325 T73 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 25326 T73 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 25326 T73 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 25327 T73 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 25328 T73 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 25328 T73 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 25334 T73 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 25349 T73 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 25351 T73 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 25353 T73 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 25354 T73 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 25356 T73 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 25368 T73 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 25371 T73 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 25373 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 25378 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 25380 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 25384 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 25385 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 25387 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 25388 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 25390 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 25394 T73 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 25396 T73 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 25408 T73 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25410 T73 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25412 T73 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25422 T73 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25424 T73 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 25428 T73 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 25428 T73 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 25431 T73 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5918691378609128] [junit4] 2> 25433 T73 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3772eb31 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4155dc9d),segFN=segments_1,generation=1} [junit4] 2> 25433 T73 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 25434 T73 oass.SolrIndexSearcher.<init> Opening Searcher@4f8a4919[collection1] main [junit4] 2> 25435 T73 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 25441 T73 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 25441 T73 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 25443 T73 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 25443 T73 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 25443 T73 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 25445 T73 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 25446 T73 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 25446 T73 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 25446 T73 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 25449 T74 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f8a4919[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 25451 T73 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 25453 T77 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49211/eq_t collection:collection1 shard:shard2 [junit4] 2> 25456 T11 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 25456 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 25470 T77 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 25490 T77 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 25495 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 25499 T77 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 25499 T77 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@5463410 url=http://127.0.0.1:49211/eq_t/collection1 node=127.0.0.1:49211_eq_t C1_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:49211/eq_t, node_name=127.0.0.1:49211_eq_t, state=down} [junit4] 2> 25500 T77 C1 P49211 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49211/eq_t/collection1/ [junit4] 2> 25501 T77 C1 P49211 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 25501 T77 C1 P49211 oasc.SyncStrategy.syncToMe http://127.0.0.1:49211/eq_t/collection1/ has no replicas [junit4] 2> 25501 T77 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49211/eq_t/collection1/ shard2 [junit4] 2> 25502 T77 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 25503 T41 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 25509 T41 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 25515 T72 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 25520 T72 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 25532 T41 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:49211/eq_t", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 25536 T41 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 25539 T72 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 25541 T72 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 25562 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 25584 T77 oasc.ZkController.register We are http://127.0.0.1:49211/eq_t/collection1/ and leader is http://127.0.0.1:49211/eq_t/collection1/ [junit4] 2> 25586 T77 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49211/eq_t [junit4] 2> 25586 T77 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 25587 T77 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 25587 T77 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 25592 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 25609 T41 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:49211/eq_t", [junit4] 2> "node_name":"127.0.0.1:49211_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 25625 T41 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:49211/eq_t", [junit4] 2> "node_name":"127.0.0.1:49211_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 25631 T41 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 25643 T72 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 25645 T77 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 25651 T72 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 27339 T11 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/cores/collection1 [junit4] 2> 27342 T11 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 27343 T11 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 27351 T11 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@686feeb9{/eq_t,null,AVAILABLE} [junit4] 2> 27352 T11 oejs.AbstractConnector.doStart Started ServerConnector@701906f5{HTTP/1.1}{127.0.0.1:49214} [junit4] 2> 27352 T11 oejs.Server.doStart Started @31495ms [junit4] 2> 27353 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 27354 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 27354 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001 [junit4] 2> 27355 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/' [junit4] 2> 27406 T11 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/solr.xml [junit4] 2> 27423 T11 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/cores [junit4] 2> 27426 T11 oasc.CoreContainer.<init> New CoreContainer 741778657 [junit4] 2> 27427 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/] [junit4] 2> 27429 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 27430 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 27431 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 27431 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 27432 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 27433 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 27434 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 27434 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 27435 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 27435 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 27435 T11 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 27436 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 27436 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 27437 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 27437 T11 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 27438 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49203/solr [junit4] 2> 27439 T11 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 27439 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 27443 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 27452 T91 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@128d14d7 name:ZooKeeperConnection Watcher:127.0.0.1:49203 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 27453 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 27454 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 27460 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 27465 T94 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@79a70c39 name:ZooKeeperConnection Watcher:127.0.0.1:49203/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 27466 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 27491 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 28515 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49214_eq_t [junit4] 2> 28519 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49214_eq_t [junit4] 2> 28535 T11 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 28543 T11 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/cores [junit4] 2> 28546 T11 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/cores/collection1/ [junit4] 2> 28546 T11 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 28556 T95 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 28556 T95 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 28578 T40 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 28584 T41 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:49214/eq_t", [junit4] 2> "node_name":"127.0.0.1:49214_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 28586 T41 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:49214/eq_t", [junit4] 2> "node_name":"127.0.0.1:49214_eq_t", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 28587 T41 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 28590 T41 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 28596 T41 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 28597 T95 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 28599 T95 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 28600 T72 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 28602 T72 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 28603 T95 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 28607 T95 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 28607 T95 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 28607 T95 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 28607 T95 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 28609 T95 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 28610 T95 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 28613 T95 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 28613 T95 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/cores/collection1/' [junit4] 2> 28646 T95 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 28666 T95 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 28707 T95 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 28716 T95 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 28732 T95 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 28737 T95 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 28765 T95 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 28990 T95 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 29007 T95 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 29013 T95 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 29080 T95 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 29092 T95 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 29098 T95 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 29102 T95 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 29103 T95 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 29103 T95 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 29105 T95 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 29106 T95 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 29106 T95 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 29106 T95 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 29107 T95 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/shard-2-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty2/ [junit4] 2> 29107 T95 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@35e122cc [junit4] 2> 29108 T95 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty2 [junit4] 2> 29109 T95 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty2/index/ [junit4] 2> 29109 T95 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 29110 T95 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty2/index [junit4] 2> 29110 T95 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 29114 T95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7bcf2830 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ae20f9d),segFN=segments_1,generation=1} [junit4] 2> 29114 T95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 29136 T95 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 29137 T95 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 29137 T95 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 29140 T95 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 29141 T95 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 29141 T95 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 29154 T95 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 29155 T95 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 29155 T95 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 29156 T95 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29158 T95 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29159 T95 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29160 T95 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29161 T95 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 29174 T95 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 29193 T95 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 29196 T95 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 29197 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 29207 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 29209 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 29211 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 29213 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 29216 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 29220 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 29221 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 29222 T95 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 29223 T95 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 29232 T95 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29235 T95 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29236 T95 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29239 T95 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29240 T95 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 29244 T95 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 29244 T95 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 29247 T95 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5918691378609128] [junit4] 2> 29248 T95 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7bcf2830 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ae20f9d),segFN=segments_1,generation=1} [junit4] 2> 29248 T95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 29249 T95 oass.SolrIndexSearcher.<init> Opening Searcher@56374d39[collection1] main [junit4] 2> 29250 T95 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 29255 T95 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 29256 T95 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 29260 T95 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 29260 T95 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 29262 T95 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 29263 T95 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 29264 T95 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 29264 T95 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 29264 T95 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 29267 T96 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@56374d39[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 29268 T95 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 29271 T99 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49214/eq_t collection:collection1 shard:shard1 [junit4] 2> 29274 T11 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 29274 T11 oass.SolrDispatchFilter.init SolrDispatchFilter. [...truncated too long message...] hard1 [junit4] 2> 52277 T141 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3623b403 [junit4] 2> 52277 T141 oasc.ZkController.unRegisterConfListener a listener was removed because of core close [junit4] 2> 52277 T141 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 52277 T141 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 52277 T141 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 52278 T141 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> 52287 T141 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 52323 T141 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 52323 T141 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty4 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty4;done=false>>] [junit4] 2> 52324 T141 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty4 [junit4] 2> 52324 T141 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty4/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty4/index;done=false>>] [junit4] 2> 52324 T141 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001/tempDir-001/jetty4/index [junit4] 2> 52324 T141 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 52325 T141 oasc.SolrException.log ERROR There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:147) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:308) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:390) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:263) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:293) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:290) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:290) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:485) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:454) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:441) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:139) [junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135) [junit4] 2> ... 11 more [junit4] 2> [junit4] 2> 53633 T196 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 53640 T11 oejs.AbstractConnector.doStop Stopped ServerConnector@4e5138a5{HTTP/1.1}{127.0.0.1:0} [junit4] 2> 53642 T11 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@399ab8b1{/eq_t,null,UNAVAILABLE} [junit4] 2> 53647 T11 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:49203 49203 [junit4] 2> 53687 T12 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:49203 49203 [junit4] 2> 54026 T12 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 8 /solr/aliases.json [junit4] 2> 8 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> 4 /solr/collections/delLiveColl/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 8 /solr/live_nodes [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 4 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DeleteReplicaTest -Dtests.method=deleteLiveReplicaTest -Dtests.seed=BAEC14A249145254 -Dtests.slow=true -Dtests.locale=ru -Dtests.timezone=Asia/Urumqi -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 51.2s | DeleteReplicaTest.deleteLiveReplicaTest <<< [junit4] > Throwable #1: java.lang.AssertionError: Should have had a good message here [junit4] > at __randomizedtesting.SeedInfo.seed([BAEC14A249145254:178CA0A9542BFA21]:0) [junit4] > at org.apache.solr.cloud.DeleteReplicaTest.deleteLiveReplicaTest(DeleteReplicaTest.java:125) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 54089 T11 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest BAEC14A249145254-001 [junit4] 2> 52041 T10 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=DefaultSimilarity, locale=ru, timezone=Asia/Urumqi [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_31 (64-bit)/cpus=2,threads=1,free=108347064,total=220725248 [junit4] 2> NOTE: All tests run in this JVM: [DeleteReplicaTest] [junit4] Completed in 55.09s, 1 test, 1 failure <<< FAILURES! [...truncated 1443 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 464 suites, 1854 tests, 1 failure, 51 ignored (20 assumptions) Total time: 129 minutes 15 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org