You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/01/10 14:38:19 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1917
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1917/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.testDistribSearch
Error Message:
There are still nodes recoverying - waited for 30 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
at __randomizedtesting.SeedInfo.seed([8649AA6FF51BC67E:7AF24778244A642]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:178)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:837)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1456)
at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.doTest(DistribDocExpirationUpdateProcessorTest.java:69)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source)
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 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 9194 lines...]
[junit4] Suite: org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/init-core-data-001
[junit4] 2> 966839 T5112 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 966840 T5112 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 966843 T5112 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 966845 T5112 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 966846 T5113 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 966948 T5112 oasc.ZkTestServer.run start zk server on port:50311
[junit4] 2> 966948 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 966950 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 966957 T5120 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@45f38be8 name:ZooKeeperConnection Watcher:127.0.0.1:50311 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 966958 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 966958 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 966959 T5112 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 966966 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 966968 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 966972 T5123 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32c4363c name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 966973 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 966974 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 966974 T5112 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 966980 T5112 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 966985 T5112 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 966990 T5112 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 966994 T5112 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-doc-expire-update-processor.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 966995 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 967002 T5112 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 967003 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 967008 T5112 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> 967008 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 967012 T5112 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> 967013 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 967019 T5112 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> 967019 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 967023 T5112 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> 967024 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 967029 T5112 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> 967030 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 967033 T5112 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> 967034 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 967042 T5112 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> 967042 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 967052 T5112 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> 967053 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 967072 T5112 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> 967074 T5112 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 967087 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 967088 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 967092 T5126 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c0cd0e6 name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 967093 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 967093 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 968846 T5112 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 968851 T5112 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@558234ab{/,null,AVAILABLE}
[junit4] 2> 968865 T5112 oejs.AbstractConnector.doStart Started ServerConnector@52dfb6fa{SSL-http/1.1}{127.0.0.1:50315}
[junit4] 2> 968865 T5112 oejs.Server.doStart Started @971143ms
[junit4] 2> 968875 T5112 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 968875 T5112 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 968875 T5112 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-002
[junit4] 2> 968875 T5112 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-002/'
[junit4] 2> 968914 T5112 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-002/solr.xml
[junit4] 2> 968936 T5112 oasc.CoreContainer.<init> New CoreContainer 1538542282
[junit4] 2> 968936 T5112 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-002/]
[junit4] 2> 968941 T5112 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 968942 T5112 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 968942 T5112 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 968943 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 968943 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 968943 T5112 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 968944 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 968944 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 968944 T5112 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 968945 T5112 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 968948 T5112 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 968949 T5112 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 968950 T5112 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 968950 T5112 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 968951 T5112 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50311/solr
[junit4] 2> 968951 T5112 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 968952 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 968953 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 968958 T5140 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@609f90f name:ZooKeeperConnection Watcher:127.0.0.1:50311 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 968959 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 968959 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 968964 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 968968 T5143 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13c8b75c name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 968969 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 968974 T5112 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 968981 T5112 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 968988 T5112 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 968995 T5112 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 969002 T5112 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 969011 T5112 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 969015 T5112 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50315_
[junit4] 2> 969016 T5112 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50315_
[junit4] 2> 969023 T5112 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 969028 T5112 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 969032 T5112 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 969038 T5112 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50315_
[junit4] 2> 969038 T5112 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 969041 T5112 oasc.Overseer.start Overseer (id=93119601916182532-127.0.0.1:50315_-n_0000000000) starting
[junit4] 2> 969046 T5112 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 969066 T5112 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 969068 T5144 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 969069 T5145 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 969073 T5112 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 969077 T5112 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 969080 T5112 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 969102 T5147 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 969102 T5147 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 969104 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 969104 T5147 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 969113 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:50315",
[junit4] 2> "node_name":"127.0.0.1:50315_",
[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> 969116 T5144 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:50315",
[junit4] 2> "node_name":"127.0.0.1:50315_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 969116 T5144 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 969116 T5144 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 969119 T5143 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> 970106 T5147 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 970107 T5147 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 970108 T5147 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 970109 T5147 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 970112 T5147 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 970112 T5147 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-002/collection1/'
[junit4] 2> 970113 T5147 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 970113 T5147 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 970114 T5147 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 970141 T5147 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 970146 T5147 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 970156 T5147 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 970165 T5147 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 970181 T5147 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 970185 T5147 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 970188 T5147 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 970335 T5147 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 970336 T5147 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 970337 T5147 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 970343 T5147 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 970386 T5147 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 970386 T5147 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 970387 T5147 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/control/data/
[junit4] 2> 970387 T5147 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 970387 T5147 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/control/data
[junit4] 2> 970388 T5147 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/control/data/index/
[junit4] 2> 970388 T5147 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 970388 T5147 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/control/data/index
[junit4] 2> 970388 T5147 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5311439801742843]
[junit4] 2> 970390 T5147 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f5331ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70fc2d3a),segFN=segments_1,generation=1}
[junit4] 2> 970390 T5147 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 970391 T5147 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-defaults"
[junit4] 2> 970463 T5147 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field"
[junit4] 2> 970464 T5147 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-param"
[junit4] 2> 970464 T5147 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
[junit4] 2> 970464 T5147 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 970537 T5147 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 970538 T5147 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 970538 T5147 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 970538 T5147 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 970538 T5147 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 970539 T5147 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 970539 T5147 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 970539 T5147 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 970539 T5147 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 970540 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 970541 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 970541 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 970541 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 970542 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 970542 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 970542 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 970543 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 970543 T5147 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 970543 T5147 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 970549 T5147 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 970551 T5147 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 970553 T5147 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 970554 T5147 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 970555 T5147 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 970557 T5147 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 970557 T5147 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 970558 T5147 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 970558 T5147 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f5331ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70fc2d3a),segFN=segments_1,generation=1}
[junit4] 2> 970558 T5147 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 970559 T5147 oass.SolrIndexSearcher.<init> Opening Searcher@254f7b86[collection1] main
[junit4] 2> 970559 T5147 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 970561 T5147 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 970561 T5147 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 970562 T5147 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 970562 T5147 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 970563 T5147 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 970564 T5147 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 970564 T5147 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 970564 T5147 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 970579 T5147 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 970580 T5147 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 970580 T5148 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@254f7b86[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 970583 T5152 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50315 collection:control_collection shard:shard1
[junit4] 2> 970584 T5112 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 970584 T5112 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 970588 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 970590 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 970594 T5155 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f4e80d name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 970594 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 970595 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 970598 T5112 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 970603 T5112 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 970603 T5112 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2
[junit4] 2> 970603 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 970605 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 970615 T5152 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 970618 T5158 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@710c81c2 name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 970619 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 970620 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 970630 T5152 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 970652 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 970661 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 1
[junit4] 2> 970661 T5144 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 970671 T5152 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 970671 T5152 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1352 name=collection1 org.apache.solr.core.SolrCore@53ee8df url=https://127.0.0.1:50315/collection1 node=127.0.0.1:50315_ C1352_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:50315, node_name=127.0.0.1:50315_, state=down}
[junit4] 2> 970672 T5152 C1352 P50315 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50315/collection1/
[junit4] 2> 970672 T5152 C1352 P50315 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 970672 T5152 C1352 P50315 oasc.SyncStrategy.syncToMe https://127.0.0.1:50315/collection1/ has no replicas
[junit4] 2> 970673 T5152 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50315/collection1/ shard1
[junit4] 2> 970673 T5152 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 970682 T5144 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json
[junit4] 2> 970685 T5155 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> 970686 T5143 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> 970719 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 2
[junit4] 2> 970746 T5159 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 970754 T5144 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":"https://127.0.0.1:50315",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 970773 T5159 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 970892 T5155 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> 970984 T5152 oasc.ZkController.register We are https://127.0.0.1:50315/collection1/ and leader is https://127.0.0.1:50315/collection1/
[junit4] 2> 970984 T5152 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50315
[junit4] 2> 970985 T5152 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 970985 T5152 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 970985 T5152 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 971007 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 971035 T5144 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":"https://127.0.0.1:50315",
[junit4] 2> "node_name":"127.0.0.1:50315_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 971042 T5144 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":"https://127.0.0.1:50315",
[junit4] 2> "node_name":"127.0.0.1:50315_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 971178 T5143 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> 971178 T5155 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> 972721 T5112 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 972723 T5112 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 972729 T5112 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@398246d9{/,null,AVAILABLE}
[junit4] 2> 972734 T5112 oejs.AbstractConnector.doStart Started ServerConnector@3a994b58{SSL-http/1.1}{127.0.0.1:50320}
[junit4] 2> 972734 T5112 oejs.Server.doStart Started @975010ms
[junit4] 2> 972737 T5112 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 972738 T5112 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 972738 T5112 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-003
[junit4] 2> 972738 T5112 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-003/'
[junit4] 2> 972784 T5112 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-003/solr.xml
[junit4] 2> 972803 T5112 oasc.CoreContainer.<init> New CoreContainer 883886380
[junit4] 2> 972803 T5112 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-003/]
[junit4] 2> 972804 T5112 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 972804 T5112 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 972804 T5112 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 972804 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 972805 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 972805 T5112 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 972806 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 972806 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 972807 T5112 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 972807 T5112 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 972810 T5112 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 972813 T5112 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 972813 T5112 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 972813 T5112 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 972814 T5112 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50311/solr
[junit4] 2> 972814 T5112 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 972814 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 972816 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 972826 T5173 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@24310434 name:ZooKeeperConnection Watcher:127.0.0.1:50311 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 972827 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 972829 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 972835 T5114 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ad3c8d9d70007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 972840 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 972844 T5176 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4952ae61 name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 972847 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 972861 T5112 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> ASYNC NEW_CORE C1353 name=collection1 org.apache.solr.core.SolrCore@53ee8df url=https://127.0.0.1:50315/collection1 node=127.0.0.1:50315_ C1353_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:50315, node_name=127.0.0.1:50315_, state=active, leader=true}
[junit4] 2> 973583 T5149 C1353 P50315 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Begining periodic deletion of expired docs
[junit4] 2> 973588 T5149 C1353 P50315 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 973588 T5149 C1353 P50315 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 973589 T5149 C1353 P50315 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 973589 T5149 C1353 P50315 oasup.LogUpdateProcessor.finish [collection1] {deleteByQuery={!cache=false}eXpField_tdt:[* TO 2015-01-10T12:19:29.954Z] (-1489913637718982656),commit=} 0 6
[junit4] 2> 973589 T5149 C1353 P50315 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Finished periodic deletion of expired docs
[junit4] 2> 973877 T5112 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50320_
[junit4] 2> 973880 T5112 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50320_
[junit4] 2> 973891 T5112 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 973913 T5177 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 973914 T5177 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 973919 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 973921 T5177 oasc.ZkController.preRegister Registering watch for external collection collection1
[junit4] 2> 973921 T5177 oascc.ZkStateReader.addZkWatch addZkWatch collection1
[junit4] 2> 973922 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:50320",
[junit4] 2> "node_name":"127.0.0.1:50320_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 973924 T5144 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:50320",
[junit4] 2> "node_name":"127.0.0.1:50320_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 973924 T5144 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 973924 T5144 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 973929 T5177 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 973932 T5177 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0
[junit4] 2> 973933 T5177 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 973936 T5144 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 973937 T5176 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 973939 T5176 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1
[junit4] 2> 974934 T5177 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 974935 T5177 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 974936 T5177 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 974937 T5177 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 974939 T5177 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 974940 T5177 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-003/collection1/'
[junit4] 2> 974941 T5177 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 974942 T5177 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 974943 T5177 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 974971 T5177 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 974976 T5177 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 974989 T5177 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 974998 T5177 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 975008 T5177 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 975013 T5177 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 975017 T5177 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 975160 T5177 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 975161 T5177 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 975163 T5177 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 975168 T5177 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 975240 T5177 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 975241 T5177 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 975241 T5177 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty1/
[junit4] 2> 975242 T5177 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 975242 T5177 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty1
[junit4] 2> 975243 T5177 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty1/index/
[junit4] 2> 975243 T5177 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 975243 T5177 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty1/index
[junit4] 2> 975244 T5177 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5311439801742843]
[junit4] 2> 975245 T5177 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1415f3a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@789ee3a),segFN=segments_1,generation=1}
[junit4] 2> 975245 T5177 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975247 T5177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-defaults"
[junit4] 2> 975248 T5177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field"
[junit4] 2> 975248 T5177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-param"
[junit4] 2> 975249 T5177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
[junit4] 2> 975250 T5177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 975251 T5177 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 975251 T5177 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 975252 T5177 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 975252 T5177 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 975252 T5177 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 975253 T5177 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 975253 T5177 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 975254 T5177 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 975254 T5177 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 975255 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 975256 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 975256 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 975257 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 975257 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 975258 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 975258 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 975259 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 975259 T5177 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 975259 T5177 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 975269 T5177 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 975271 T5177 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 975273 T5177 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 975274 T5177 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 975275 T5177 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 975277 T5177 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 975278 T5177 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 975278 T5177 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 975279 T5177 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1415f3a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@789ee3a),segFN=segments_1,generation=1}
[junit4] 2> 975279 T5177 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975280 T5177 oass.SolrIndexSearcher.<init> Opening Searcher@52573d27[collection1] main
[junit4] 2> 975280 T5177 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 975283 T5177 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 975283 T5177 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 975286 T5177 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 975286 T5177 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 975286 T5177 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 975290 T5177 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 975290 T5177 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 975290 T5177 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 975291 T5177 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 975293 T5177 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 975293 T5178 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52573d27[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 975296 T5182 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50320 collection:collection1 shard:shard2
[junit4] 2> 975297 T5112 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 975297 T5112 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 975298 T5182 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 975311 T5182 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 975317 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 975319 T5182 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 975319 T5182 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1354 name=collection1 org.apache.solr.core.SolrCore@4b40561 url=https://127.0.0.1:50320/collection1 node=127.0.0.1:50320_ C1354_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:50320, node_name=127.0.0.1:50320_, state=down}
[junit4] 2> 975319 T5182 C1354 P50320 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50320/collection1/
[junit4] 2> 975320 T5182 C1354 P50320 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 975320 T5182 C1354 P50320 oasc.SyncStrategy.syncToMe https://127.0.0.1:50320/collection1/ has no replicas
[junit4] 2> 975320 T5182 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50320/collection1/ shard2
[junit4] 2> 975321 T5182 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 975335 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 975350 T5144 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 975360 T5176 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 975376 T5176 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2
[junit4] 2> 975380 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:50320",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 975383 T5144 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2
[junit4] 2> 975385 T5176 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 975388 T5176 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3
[junit4] 2> 975396 T5182 oasc.ZkController.register We are https://127.0.0.1:50320/collection1/ and leader is https://127.0.0.1:50320/collection1/
[junit4] 2> 975396 T5182 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50320
[junit4] 2> 975397 T5182 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 975397 T5182 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 975397 T5182 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 975404 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 975414 T5144 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":"https://127.0.0.1:50320",
[junit4] 2> "node_name":"127.0.0.1:50320_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 975416 T5144 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":"https://127.0.0.1:50320",
[junit4] 2> "node_name":"127.0.0.1:50320_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 975430 T5144 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3
[junit4] 2> 975436 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 975438 T5176 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 975446 T5182 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4
[junit4] 2> 975448 T5176 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4
[junit4] 2> ASYNC NEW_CORE C1355 name=collection1 org.apache.solr.core.SolrCore@53ee8df url=https://127.0.0.1:50315/collection1 node=127.0.0.1:50315_ C1355_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:50315, node_name=127.0.0.1:50315_, state=active, leader=true}
[junit4] 2> 976583 T5149 C1355 P50315 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Begining periodic deletion of expired docs
[junit4] 2> 976584 T5149 C1355 P50315 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 976585 T5149 C1355 P50315 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 976585 T5149 C1355 P50315 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 976586 T5149 C1355 P50315 oasup.LogUpdateProcessor.finish [collection1] {deleteByQuery={!cache=false}eXpField_tdt:[* TO 2015-01-10T12:19:32.954Z] (-1489913640863662080),commit=} 0 3
[junit4] 2> 976586 T5149 C1355 P50315 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Finished periodic deletion of expired docs
[junit4] 2> 977088 T5112 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 977091 T5112 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 977097 T5112 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@114caa9c{/,null,AVAILABLE}
[junit4] 2> 977107 T5112 oejs.AbstractConnector.doStart Started ServerConnector@6d904ba7{SSL-http/1.1}{127.0.0.1:50323}
[junit4] 2> 977108 T5112 oejs.Server.doStart Started @979382ms
[junit4] 2> 977115 T5112 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 977115 T5112 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 977116 T5112 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-004
[junit4] 2> 977119 T5112 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-004/'
[junit4] 2> 977173 T5112 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-004/solr.xml
[junit4] 2> 977193 T5112 oasc.CoreContainer.<init> New CoreContainer 139405719
[junit4] 2> 977193 T5112 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-004/]
[junit4] 2> 977194 T5112 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 977194 T5112 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 977194 T5112 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 977195 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 977195 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 977195 T5112 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 977196 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 977196 T5112 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 977197 T5112 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 977197 T5112 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 977205 T5112 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 977213 T5112 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 977213 T5112 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 977213 T5112 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 977214 T5112 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50311/solr
[junit4] 2> 977214 T5112 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 977214 T5112 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 977218 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 977225 T5196 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@245152fb name:ZooKeeperConnection Watcher:127.0.0.1:50311 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 977226 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 977226 T5112 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 977237 T5112 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 977250 T5199 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6063d9e6 name:ZooKeeperConnection Watcher:127.0.0.1:50311/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 977251 T5112 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 977272 T5112 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 978291 T5112 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50323_
[junit4] 2> 978293 T5112 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50323_
[junit4] 2> ASYNC NEW_CORE C1356 name=collection1 org.apache.solr.core.SolrCore@4b40561 url=https://127.0.0.1:50320/collection1 node=127.0.0.1:50320_ C1356_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:50320, node_name=127.0.0.1:50320_, state=active, leader=true}
[junit4] 2> 978294 T5179 C1356 P50320 oasup.DocExpirationUpdateProcessorFactory.iAmInChargeOfPeriodicDeletes WARN Slice in charge of periodic deletes for collection1 does not currently have a leader
[junit4] 2> 978305 T5112 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 978321 T5200 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 978321 T5200 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 978326 T5143 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 978328 T5200 oasc.ZkController.preRegister Registering watch for external collection collection1
[junit4] 2> 978328 T5200 oascc.ZkStateReader.addZkWatch addZkWatch collection1
[junit4] 2> 978328 T5144 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:50323",
[junit4] 2> "node_name":"127.0.0.1:50323_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 978330 T5200 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 978331 T5144 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:50323",
[junit4] 2> "node_name":"127.0.0.1:50323_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 978332 T5144 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 978332 T5144 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 978334 T5200 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4
[junit4] 2> 978334 T5200 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 978335 T5144 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4
[junit4] 2> 978336 T5176 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 978337 T5199 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 978338 T5176 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5
[junit4] 2> 978338 T5199 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5
[junit4] 2> 979336 T5200 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 979336 T5200 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 979338 T5200 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 979338 T5200 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 979341 T5200 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 979341 T5200 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-004/collection1/'
[junit4] 2> 979342 T5200 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 979343 T5200 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 979343 T5200 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%208649AA6FF51BC67E-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 979371 T5200 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 979378 T5200 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 979390 T5200 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 979399 T5200 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 979417 T5200 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 979421 T5200 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 979427 T5200 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 979582 T5200 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 979583 T5200 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> ASYNC NEW_CORE C1357 name=collection1 org.apache.solr.core.SolrCore@53ee8df url=https://127.0.0.1:50315/collection1 node=127.0.0.1:50315_ C1357_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:50315, node_name=127.0.0.1:50315_, state=active, leader=true}
[junit4] 2> 979585 T5149 C1357 P50315 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Begining periodic deletion of expired docs
[junit4] 2> 979586 T5200 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 979587 T5149 C1357 P50315 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 979587 T5149 C1357 P50315 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 979588 T5149 C1357 P50315 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 979588 T5149 C1357 P50315 oasup.LogUpdateProcessor.finish [collection1] {deleteByQuery={!cache=false}eXpField_tdt:[* TO 2015-01-10T12:19:35.956Z] (-1489913644011487232),commit=} 0 3
[junit4] 2> 979588 T5149 C1357 P50315 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Finished periodic deletion of expired docs
[junit4] 2> 979591 T5200 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 979648 T5200 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 979649 T5200 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 979649 T5200 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty2/
[junit4] 2> 979649 T5200 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 979650 T5200 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty2
[junit4] 2> 979650 T5200 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty2/index/
[junit4] 2> 979650 T5200 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 979650 T5200 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001/tempDir-001/jetty2/index
[junit4] 2> 979651 T5200 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5311439801742843]
[junit4] 2> 979651 T5200 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@280cad93 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c52b7d1),segFN=segments_1,generation=1}
[junit4] 2> 979652 T5200 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 979653 T5200 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-defaults"
[junit4] 2> 979653 T5200 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field"
[junit4] 2> 979653 T5200 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-param"
[junit4] 2> 979653 T5200 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
[junit4] 2> 979654 T5200 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 979654 T5200 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 979654 T5200 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979655 T5200 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979655 T5200 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979655 T5200 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979655 T5200 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 979656 T5200 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 979656 T5200 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 979656 T5200 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 979656 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 979659 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 979659 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 979660 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 979660 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 979660 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 979661 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 979662 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 979662 T5200 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 979662 T5200 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 979672 T5200 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979673 T5200 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979674 T5200 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979675 T5200 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979675 T5200 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 979679 T5200 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 979680 T5200 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 979680 T5200 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[...truncated too long message...]
a:256)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:469)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:226)
[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 /overseer
[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.create(ZooKeeper.java:783)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:379)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:376)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:376)
[junit4] 2> at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:1008)
[junit4] 2> ... 25 more
[junit4] 2>
[junit4] 2> 1031620 T5112 oejs.AbstractConnector.doStop Stopped ServerConnector@3ab45326{SSL-http/1.1}{127.0.0.1:0}
[junit4] 2> 1031621 T5112 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@63c81629{/,null,UNAVAILABLE}
[junit4] 2> 1031624 T5112 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 1031627 T5112 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50311 50311
[junit4] 2> 1031661 T5113 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50311 50311
[junit4] 2> 1031880 T5113 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistribDocExpirationUpdateProcessorTest -Dtests.method=testDistribSearch -Dtests.seed=8649AA6FF51BC67E -Dtests.slow=true -Dtests.locale=it -Dtests.timezone=Africa/Sao_Tome -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 65.1s | DistribDocExpirationUpdateProcessorTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
[junit4] > at __randomizedtesting.SeedInfo.seed([8649AA6FF51BC67E:7AF24778244A642]:0)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:178)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:837)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1456)
[junit4] > at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.doTest(DistribDocExpirationUpdateProcessorTest.java:69)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1031896 T5112 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.DistribDocExpirationUpdateProcessorTest 8649AA6FF51BC67E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=PostingsFormat(name=Asserting), id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=it, timezone=Africa/Sao_Tome
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=2,threads=1,free=63920088,total=133861376
[junit4] 2> NOTE: All tests run in this JVM: [MigrateRouteKeyTest, ClusterStateTest, SharedFSAutoReplicaFailoverTest, TestDistributedMissingSort, BasicZkTest, TestLRUStatsCache, TestDistribDocBasedVersion, SolrPluginUtilsTest, TestPHPSerializedResponseWriter, ResponseHeaderTest, TestSchemaVersionResource, TestQuerySenderNoQuery, TestExpandComponent, TestLMJelinekMercerSimilarityFactory, TestFieldTypeResource, TestReversedWildcardFilterFactory, AddSchemaFieldsUpdateProcessorFactoryTest, DistributedQueryComponentOptimizationTest, RankQueryTest, TestDistributedGrouping, TestNoOpRegenerator, UpdateRequestProcessorFactoryTest, ParsingFieldUpdateProcessorsTest, TestOrdValues, TestBlendedInfixSuggestions, SolrXmlInZkTest, RequiredFieldsTest, BinaryUpdateRequestHandlerTest, SpellingQueryConverterTest, DistributedTermsComponentTest, DateRangeFieldTest, TestConfigOverlay, SolrIndexConfigTest, SynonymTokenizerTest, SolrTestCaseJ4Test, TestSolrConfigHandler, TestGroupingSearch, MoreLikeThisHandlerTest, TestExactStatsCache, TestPseudoReturnFields, OutOfBoxZkACLAndCredentialsProvidersTest, HdfsSyncSliceTest, BufferStoreTest, RequestHandlersTest, CursorPagingTest, JsonLoaderTest, DocValuesMissingTest, TestRawResponseWriter, SSLMigrationTest, TestReplicationHandler, TestDefaultStatsCache, HttpPartitionTest, TestCollationField, TestUniqueKeyFieldResource, AliasIntegrationTest, TestManagedResource, TestDynamicFieldCollectionResource, SliceStateTest, UniqFieldsUpdateProcessorFactoryTest, SystemInfoHandlerTest, PreAnalyzedFieldTest, DocValuesMultiTest, TestFieldTypeCollectionResource, TestCSVLoader, AnalyticsQueryTest, TestSchemaNameResource, TestManagedStopFilterFactory, TestExceedMaxTermLength, SolrInfoMBeanTest, SolrRequestParserTest, TestCodecSupport, TestDocSet, PluginInfoTest, LeaderFailoverAfterPartitionTest, TestConfigReload, TestFastWriter, TestCustomSort, TestXIncludeConfig, ShowFileRequestHandlerTest, TestAtomicUpdateErrorCases, ChaosMonkeyNothingIsSafeTest, TestStressLucene, DistanceUnitsTest, ClusterStateUpdateTest, TestWordDelimiterFilterFactory, XsltUpdateRequestHandlerTest, TestAddFieldRealTimeGet, TermVectorComponentTest, BJQParserTest, HdfsRecoveryZkTest, RollingRestartTest, TestQuerySenderListener, FastVectorHighlighterTest, FieldAnalysisRequestHandlerTest, TestReplicationHandlerBackup, CachingDirectoryFactoryTest, HighlighterTest, TestCharFilters, MinimalSchemaTest, HdfsUnloadDistributedZkTest, ScriptEngineTest, TestSolrXmlPersistence, AddBlockUpdateTest, TestQueryUtils, TestExactSharedStatsCache, SolrCoreTest, AnalyticsMergeStrategyTest, TestIndexSearcher, PrimitiveFieldTypeTest, DisMaxRequestHandlerTest, OutputWriterTest, TestIBSimilarityFactory, TestSolrQueryParserDefaultOperatorResource, PathHierarchyTokenizerFactoryTest, TestManagedResourceStorage, TestRangeQuery, TimeZoneUtilsTest, UUIDUpdateProcessorFallbackTest, HdfsBasicDistributedZkTest, TestManagedSynonymFilterFactory, DefaultValueUpdateProcessorTest, OverseerCollectionProcessorTest, TestManagedSchemaFieldTypeResource, AtomicUpdatesTest, SimplePostToolTest, DistributedQueueTest, HardAutoCommitTest, TestCoreDiscovery, LoggingHandlerTest, FieldMutatingUpdateProcessorTest, TestInfoStreamLogging, DistribDocExpirationUpdateProcessorTest]
[junit4] Completed in 65.10s, 1 test, 1 failure <<< FAILURES!
[...truncated 1007 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:187: 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: 460 suites, 1869 tests, 1 failure, 52 ignored (21 assumptions)
Total time: 138 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1918
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1918/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.DeleteReplicaTest.testDistribSearch
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([5FCDE2D5604CA5E0:DE2B6CCD1713C5DC]: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:138)
at org.apache.solr.cloud.DeleteReplicaTest.doTest(DeleteReplicaTest.java:89)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor71.invoke(Unknown Source)
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 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 9703 lines...]
[junit4] Suite: org.apache.solr.cloud.DeleteReplicaTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/init-core-data-001
[junit4] 2> 3782980 T16251 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 3782981 T16251 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 3782984 T16251 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 3782985 T16251 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3782987 T16252 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 3783088 T16251 oasc.ZkTestServer.run start zk server on port:53427
[junit4] 2> 3783089 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3783091 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3783100 T16259 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fd748dd name:ZooKeeperConnection Watcher:127.0.0.1:53427 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3783101 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3783102 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3783102 T16251 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 3783112 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3783113 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3783116 T16262 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@45045700 name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3783116 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3783117 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3783117 T16251 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 3783127 T16251 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 3783136 T16251 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 3783143 T16251 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 3783154 T16251 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> 3783155 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 3783169 T16251 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> 3783170 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 3783179 T16251 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> 3783179 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3783186 T16251 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> 3783187 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 3783195 T16251 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> 3783196 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 3783203 T16251 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> 3783204 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 3783211 T16251 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> 3783212 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 3783220 T16251 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> 3783220 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 3783229 T16251 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> 3783230 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3783238 T16251 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> 3783238 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 3783246 T16251 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> 3783247 T16251 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 3785231 T16251 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3785239 T16251 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6383b811{/,null,AVAILABLE}
[junit4] 2> 3785239 T16251 oejs.AbstractConnector.doStart Started ServerConnector@74e0f210{HTTP/1.1}{127.0.0.1:53430}
[junit4] 2> 3785240 T16251 oejs.Server.doStart Started @3791340ms
[junit4] 2> 3785240 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3785241 T16251 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3785241 T16251 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 5FCDE2D5604CA5E0-001/tempDir-002
[junit4] 2> 3785242 T16251 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-002/'
[junit4] 2> 3785286 T16251 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 5FCDE2D5604CA5E0-001/tempDir-002/solr.xml
[junit4] 2> 3785297 T16251 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /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 5FCDE2D5604CA5E0-001/tempDir-002
[junit4] 2> 3785299 T16251 oasc.CoreContainer.<init> New CoreContainer 2009442700
[junit4] 2> 3785299 T16251 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 5FCDE2D5604CA5E0-001/tempDir-002/]
[junit4] 2> 3785300 T16251 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3785300 T16251 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3785301 T16251 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3785301 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3785302 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 3785302 T16251 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3785302 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3785303 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3785303 T16251 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3785303 T16251 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3785304 T16251 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3785305 T16251 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3785305 T16251 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3785305 T16251 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3785306 T16251 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53427/solr
[junit4] 2> 3785306 T16251 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3785306 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3785308 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3785311 T16276 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@311b8f8a name:ZooKeeperConnection Watcher:127.0.0.1:53427 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3785311 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3785312 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3785317 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3785321 T16279 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fbfd367 name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3785321 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3785325 T16251 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 3785334 T16251 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 3785343 T16251 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 3785350 T16251 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 3785357 T16251 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 3785366 T16251 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 3785369 T16251 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53430_
[junit4] 2> 3785371 T16251 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53430_
[junit4] 2> 3785382 T16251 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 3785390 T16251 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 3785398 T16251 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3785403 T16251 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53430_
[junit4] 2> 3785404 T16251 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 3785409 T16251 oasc.Overseer.start Overseer (id=93122088719548419-127.0.0.1:53430_-n_0000000000) starting
[junit4] 2> 3785416 T16251 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 3785442 T16251 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 3785444 T16281 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 3785448 T16251 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 3785455 T16251 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 3785461 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3785477 T16251 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /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 5FCDE2D5604CA5E0-001/tempDir-002
[junit4] 2> 3785477 T16280 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 3786314 T16251 oasc.CorePropertiesLocator.discoverUnder Found core conf in /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 5FCDE2D5604CA5E0-001/tempDir-002/conf/
[junit4] 2> 3786455 T16251 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 3786457 T16251 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3786457 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3786458 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3786460 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3786462 T16286 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5622d2e1 name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3786462 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3786463 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3786465 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3786475 T16251 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 3786475 T16251 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2
[junit4] 2> 3786476 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3786477 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3786481 T16289 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2553b5d3 name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3786481 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3786482 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3786486 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3786501 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 0
[junit4] 2> 3786503 T16280 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 3786519 T16280 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json
[junit4] 2> 3786523 T16286 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> 3786523 T16279 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> 3788521 T16251 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 3788525 T16251 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3788529 T16251 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@43d8c325{/,null,AVAILABLE}
[junit4] 2> 3788531 T16251 oejs.AbstractConnector.doStart Started ServerConnector@367470{HTTP/1.1}{127.0.0.1:53435}
[junit4] 2> 3788531 T16251 oejs.Server.doStart Started @3794632ms
[junit4] 2> 3788532 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3788532 T16251 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3788532 T16251 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 5FCDE2D5604CA5E0-001/tempDir-003
[junit4] 2> 3788532 T16251 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-003/'
[junit4] 2> 3788572 T16251 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 5FCDE2D5604CA5E0-001/tempDir-003/solr.xml
[junit4] 2> 3788583 T16251 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /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 5FCDE2D5604CA5E0-001/tempDir-003
[junit4] 2> 3788583 T16251 oasc.CoreContainer.<init> New CoreContainer 987022899
[junit4] 2> 3788584 T16251 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 5FCDE2D5604CA5E0-001/tempDir-003/]
[junit4] 2> 3788584 T16251 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3788585 T16251 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3788585 T16251 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3788585 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3788586 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 3788587 T16251 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3788587 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3788587 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3788588 T16251 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3788588 T16251 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3788589 T16251 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3788589 T16251 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3788589 T16251 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3788590 T16251 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3788590 T16251 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53427/solr
[junit4] 2> 3788590 T16251 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3788591 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3788592 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3788598 T16303 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7485fa0b name:ZooKeeperConnection Watcher:127.0.0.1:53427 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3788599 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3788600 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3788605 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3788608 T16306 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b2ac4da name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3788608 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3788628 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3789645 T16251 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53435_
[junit4] 2> 3789647 T16251 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53435_
[junit4] 2> 3789660 T16251 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3789670 T16251 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /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 5FCDE2D5604CA5E0-001/tempDir-003
[junit4] 2> 3790565 T16251 oasc.CorePropertiesLocator.discoverUnder Found core conf in /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 5FCDE2D5604CA5E0-001/tempDir-003/conf/
[junit4] 2> 3790682 T16251 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 3790685 T16251 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3790685 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3792765 T16251 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 3792766 T16251 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3792772 T16251 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@27a0fdd4{/,null,AVAILABLE}
[junit4] 2> 3792773 T16251 oejs.AbstractConnector.doStart Started ServerConnector@154c3669{HTTP/1.1}{127.0.0.1:53438}
[junit4] 2> 3792774 T16251 oejs.Server.doStart Started @3798874ms
[junit4] 2> 3792775 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3792776 T16251 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3792776 T16251 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 5FCDE2D5604CA5E0-001/tempDir-004
[junit4] 2> 3792777 T16251 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-004/'
[junit4] 2> 3792834 T16251 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-004/solr.xml
[junit4] 2> 3792848 T16251 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 5FCDE2D5604CA5E0-001/tempDir-004
[junit4] 2> 3792850 T16251 oasc.CoreContainer.<init> New CoreContainer 829755246
[junit4] 2> 3792850 T16251 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 5FCDE2D5604CA5E0-001/tempDir-004/]
[junit4] 2> 3792851 T16251 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3792851 T16251 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3792852 T16251 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3792852 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3792853 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 3792853 T16251 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3792854 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3792854 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3792855 T16251 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3792855 T16251 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3792856 T16251 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3792856 T16251 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3792856 T16251 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3792857 T16251 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3792857 T16251 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53427/solr
[junit4] 2> 3792857 T16251 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3792858 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3792859 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3792863 T16321 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c720594 name:ZooKeeperConnection Watcher:127.0.0.1:53427 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3792864 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3792866 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3792876 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3792880 T16324 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@43faa5df name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3792881 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3792903 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3793923 T16251 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53438_
[junit4] 2> 3793927 T16251 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53438_
[junit4] 2> 3793946 T16251 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3793961 T16251 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 5FCDE2D5604CA5E0-001/tempDir-004
[junit4] 2> 3794704 T16251 oasc.CorePropertiesLocator.discoverUnder Found core conf in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-004/conf/
[junit4] 2> 3794804 T16251 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 3794809 T16251 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3794809 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3796898 T16251 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 3796901 T16251 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3796908 T16251 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@432f9fa5{/,null,AVAILABLE}
[junit4] 2> 3796910 T16251 oejs.AbstractConnector.doStart Started ServerConnector@3495baf2{HTTP/1.1}{127.0.0.1:53441}
[junit4] 2> 3796910 T16251 oejs.Server.doStart Started @3803011ms
[junit4] 2> 3796911 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3796911 T16251 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3796912 T16251 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 5FCDE2D5604CA5E0-001/tempDir-005
[junit4] 2> 3796912 T16251 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-005/'
[junit4] 2> 3796961 T16251 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-005/solr.xml
[junit4] 2> 3796976 T16251 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 5FCDE2D5604CA5E0-001/tempDir-005
[junit4] 2> 3796978 T16251 oasc.CoreContainer.<init> New CoreContainer 2123161529
[junit4] 2> 3796978 T16251 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 5FCDE2D5604CA5E0-001/tempDir-005/]
[junit4] 2> 3796979 T16251 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3796980 T16251 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3796980 T16251 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3796980 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3796980 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 3796981 T16251 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3796981 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3796981 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3796982 T16251 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3796982 T16251 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3796983 T16251 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3796983 T16251 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3796983 T16251 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3796984 T16251 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3796985 T16251 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53427/solr
[junit4] 2> 3796985 T16251 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3796986 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3796988 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3796993 T16339 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7b0247d7 name:ZooKeeperConnection Watcher:127.0.0.1:53427 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3796994 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3796994 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3797001 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3797005 T16342 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@162e0e23 name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3797006 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3797028 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3798055 T16251 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53441_
[junit4] 2> 3798058 T16251 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53441_
[junit4] 2> 3798080 T16251 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3798093 T16251 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 5FCDE2D5604CA5E0-001/tempDir-005
[junit4] 2> 3798882 T16251 oasc.CorePropertiesLocator.discoverUnder Found core conf in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-005/conf/
[junit4] 2> 3799000 T16251 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 3799002 T16251 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3799002 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3801203 T16251 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 3801204 T16251 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3801216 T16251 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@125e8e04{/,null,AVAILABLE}
[junit4] 2> 3801216 T16251 oejs.AbstractConnector.doStart Started ServerConnector@5c69e4a6{HTTP/1.1}{127.0.0.1:53444}
[junit4] 2> 3801217 T16251 oejs.Server.doStart Started @3807318ms
[junit4] 2> 3801217 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3801218 T16251 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3801218 T16251 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 5FCDE2D5604CA5E0-001/tempDir-006
[junit4] 2> 3801219 T16251 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-006/'
[junit4] 2> 3801271 T16251 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 5FCDE2D5604CA5E0-001/tempDir-006/solr.xml
[junit4] 2> 3801287 T16251 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /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 5FCDE2D5604CA5E0-001/tempDir-006
[junit4] 2> 3801288 T16251 oasc.CoreContainer.<init> New CoreContainer 1675401489
[junit4] 2> 3801288 T16251 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 5FCDE2D5604CA5E0-001/tempDir-006/]
[junit4] 2> 3801289 T16251 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3801289 T16251 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3801290 T16251 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3801291 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3801291 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 3801291 T16251 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3801291 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3801292 T16251 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3801292 T16251 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3801293 T16251 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3801293 T16251 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3801294 T16251 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3801294 T16251 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3801295 T16251 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3801296 T16251 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53427/solr
[junit4] 2> 3801296 T16251 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3801297 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3801300 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3801303 T16357 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a69b032 name:ZooKeeperConnection Watcher:127.0.0.1:53427 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3801304 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3801304 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3801310 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3801316 T16360 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5675728 name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3801318 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3801340 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3802364 T16251 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53444_
[junit4] 2> 3802367 T16251 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53444_
[junit4] 2> 3802379 T16251 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3802389 T16251 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /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 5FCDE2D5604CA5E0-001/tempDir-006
[junit4] 2> 3803370 T16251 oasc.CorePropertiesLocator.discoverUnder Found core conf in /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 5FCDE2D5604CA5E0-001/tempDir-006/conf/
[junit4] 2> 3803531 T16251 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 3803534 T16251 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3803535 T16251 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3803540 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3803542 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3803546 T16364 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a74c50f name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3803547 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3803548 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3803552 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3803565 T16251 oasc.AbstractFullDistribZkTestBase.createCollection Creating collection with stateFormat=2: delLiveColl
[junit4] 2> 3803565 T16251 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 3803566 T16251 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3803571 T16367 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d5ec74b name:ZooKeeperConnection Watcher:127.0.0.1:53427/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3803573 T16251 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3803573 T16251 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 3803578 T16251 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3803606 T16267 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&maxShardsPerNode=2&name=delLiveColl&action=CREATE&numShards=2&stateFormat=2&wt=javabin&version=2
[junit4] 2> 3803609 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/collection-queue-work state SyncConnected
[junit4] 2> 3803613 T16281 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"delLiveColl",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "stateFormat":"2"}
[junit4] 2> 3803615 T16369 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : create , {
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"delLiveColl",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "stateFormat":"2"}
[junit4] 2> 3803619 T16369 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1
[junit4] 2> 3803620 T16369 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/delLiveColl
[junit4] 2> 3803623 T16369 oascc.SolrZkClient.makePath makePath: /collections/delLiveColl
[junit4] 2> 3803634 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3803640 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"delLiveColl",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "stateFormat":"2"} current state version: 1
[junit4] 2> 3803641 T16280 oasco.ClusterStateMutator.createCollection building a new cName: delLiveColl
[junit4] 2> 3803648 T16280 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/delLiveColl/state.json
[junit4] 2> 3803653 T16360 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803654 T16279 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803654 T16286 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803655 T16364 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803654 T16367 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803656 T16342 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803656 T16306 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803654 T16324 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3803838 T16369 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection delLiveColl, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 3803838 T16369 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard1_replica1 as part of slice shard1 of collection delLiveColl on 127.0.0.1:53444_
[junit4] 2> 3803843 T16369 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard1_replica2 as part of slice shard1 of collection delLiveColl on 127.0.0.1:53430_
[junit4] 2> 3803847 T16369 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard2_replica1 as part of slice shard2 of collection delLiveColl on 127.0.0.1:53441_
[junit4] 2> 3803850 T16347 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=delLiveColl_shard1_replica1&action=CREATE&numShards=2&collection=delLiveColl&shard=shard1&wt=javabin&version=2
[junit4] 2> 3803856 T16369 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard2_replica2 as part of slice shard2 of collection delLiveColl on 127.0.0.1:53438_
[junit4] 2> 3803859 T16266 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=delLiveColl_shard1_replica2&action=CREATE&numShards=2&collection=delLiveColl&shard=shard1&wt=javabin&version=2
[junit4] 2> 3803867 T16329 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=delLiveColl_shard2_replica1&action=CREATE&numShards=2&collection=delLiveColl&shard=shard2&wt=javabin&version=2
[junit4] 2> 3803873 T16347 oasc.ZkController.publish publishing core=delLiveColl_shard1_replica1 state=down collection=delLiveColl
[junit4] 2> 3803874 T16310 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=delLiveColl_shard2_replica2&action=CREATE&numShards=2&collection=delLiveColl&shard=shard2&wt=javabin&version=2
[junit4] 2> 3803879 T16329 oasc.ZkController.publish publishing core=delLiveColl_shard2_replica1 state=down collection=delLiveColl
[junit4] 2> 3803882 T16266 oasc.ZkController.publish publishing core=delLiveColl_shard1_replica2 state=down collection=delLiveColl
[junit4] 2> 3803883 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3803888 T16347 oasc.ZkController.preRegister Registering watch for external collection delLiveColl
[junit4] 2> 3803889 T16347 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl
[junit4] 2> 3803891 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"delLiveColl_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53444",
[junit4] 2> "node_name":"127.0.0.1:53444_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 3803891 T16310 oasc.ZkController.publish publishing core=delLiveColl_shard2_replica2 state=down collection=delLiveColl
[junit4] 2> 3803891 T16329 oasc.ZkController.preRegister Registering watch for external collection delLiveColl
[junit4] 2> 3803893 T16329 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl
[junit4] 2> 3803893 T16347 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper...
[junit4] 2> 3803897 T16280 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"delLiveColl_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53444",
[junit4] 2> "node_name":"127.0.0.1:53444_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"}
[junit4] 2> 3803899 T16329 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper...
[junit4] 2> 3803906 T16347 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0
[junit4] 2> 3803907 T16347 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3803909 T16266 oasc.ZkController.preRegister Registering watch for external collection delLiveColl
[junit4] 2> 3803911 T16329 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0
[junit4] 2> 3803912 T16329 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3803916 T16310 oasc.ZkController.preRegister Registering watch for external collection delLiveColl
[junit4] 2> 3803917 T16310 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl
[junit4] 2> 3803920 T16310 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper...
[junit4] 2> 3803922 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 3, message = {
[junit4] 2> "core":"delLiveColl_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53441",
[junit4] 2> "node_name":"127.0.0.1:53441_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 3803924 T16310 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0
[junit4] 2> 3803925 T16310 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3803924 T16280 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"delLiveColl_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53441",
[junit4] 2> "node_name":"127.0.0.1:53441_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"}
[junit4] 2> 3803932 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3803936 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 3, message = {
[junit4] 2> "core":"delLiveColl_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53430",
[junit4] 2> "node_name":"127.0.0.1:53430_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 3803958 T16280 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"delLiveColl_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53430",
[junit4] 2> "node_name":"127.0.0.1:53430_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"}
[junit4] 2> 3803968 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"delLiveColl_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53438",
[junit4] 2> "node_name":"127.0.0.1:53438_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 3803969 T16280 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"delLiveColl_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53438",
[junit4] 2> "node_name":"127.0.0.1:53438_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"delLiveColl",
[junit4] 2> "operation":"state"}
[junit4] 2> 3803975 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3804079 T16280 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/delLiveColl/state.json version: 0
[junit4] 2> 3804081 T16324 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804082 T16360 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804082 T16342 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804088 T16324 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1
[junit4] 2> 3804090 T16360 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1
[junit4] 2> 3804091 T16342 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1
[junit4] 2> 3804119 T16266 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl
[junit4] 2> 3804121 T16266 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper...
[junit4] 2> 3804128 T16266 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1
[junit4] 2> 3804128 T16266 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3804129 T16266 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard1_replica2
[junit4] 2> 3804129 T16266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl
[junit4] 2> 3804131 T16266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3804132 T16266 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl
[junit4] 2> 3804135 T16266 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 3804135 T16266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/'
[junit4] 2> 3804188 T16266 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 3804196 T16266 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 3804211 T16266 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 3804222 T16266 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 3804236 T16266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3804241 T16266 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 3804252 T16266 oass.IndexSchema.readSchema [delLiveColl_shard1_replica2] Schema name=test
[junit4] 2> 3804401 T16266 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3804413 T16266 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3804416 T16266 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3804443 T16266 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3804451 T16266 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3804458 T16266 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3804466 T16266 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3804467 T16266 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3804468 T16266 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3804473 T16266 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3804475 T16266 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3804480 T16266 oasc.CoreContainer.create Creating SolrCore 'delLiveColl_shard1_replica2' using configuration from collection delLiveColl
[junit4] 2> 3804481 T16266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3804483 T16266 oasc.SolrCore.<init> [delLiveColl_shard1_replica2] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/, dataDir=/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 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/data/
[junit4] 2> 3804484 T16266 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2fdca13d
[junit4] 2> 3804486 T16266 oasc.CachingDirectoryFactory.get return new directory for /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 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/data
[junit4] 2> 3804488 T16266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/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 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/data/index/
[junit4] 2> 3804489 T16266 oasc.SolrCore.initIndex WARN [delLiveColl_shard1_replica2] Solr index directory '/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 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 3804492 T16266 oasc.CachingDirectoryFactory.get return new directory for /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 5FCDE2D5604CA5E0-001/tempDir-002/delLiveColl_shard1_replica2/data/index
[junit4] 2> 3804493 T16266 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=47.3486328125, floorSegmentMB=1.5888671875, forceMergeDeletesPctAllowed=26.704261010426524, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 3804496 T16266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@485571b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33bd956f),segFN=segments_1,generation=1}
[junit4] 2> 3804496 T16266 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3804505 T16266 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3804507 T16266 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3804509 T16266 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3804509 T16266 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3804510 T16266 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3804510 T16266 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3804511 T16266 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3804511 T16266 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3804512 T16266 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3804513 T16266 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804514 T16266 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804515 T16266 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804516 T16266 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804518 T16266 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 3804519 T16266 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 3804521 T16266 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 3804522 T16266 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 3804523 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 3804525 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 3804526 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 3804526 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 3804527 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 3804528 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 3804529 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 3804530 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 3804531 T16266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3804532 T16266 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3804545 T16266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804550 T16266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804552 T16266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804568 T16266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804569 T16266 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3804574 T16266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3804575 T16266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3804576 T16266 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4206292716653145]
[junit4] 2> 3804577 T16266 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@485571b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33bd956f),segFN=segments_1,generation=1}
[junit4] 2> 3804578 T16266 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3804578 T16266 oass.SolrIndexSearcher.<init> Opening Searcher@6cce9284[delLiveColl_shard1_replica2] main
[junit4] 2> 3804579 T16266 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl
[junit4] 2> 3804583 T16266 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 3804584 T16266 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 3804586 T16266 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3804586 T16266 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 3804587 T16266 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3804591 T16266 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3804591 T16266 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3804592 T16266 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3804592 T16266 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 3804593 T16266 oasc.CoreContainer.registerCore registering core: delLiveColl_shard1_replica2
[junit4] 2> 3804593 T16266 oasc.ZkController.register Register replica - core:delLiveColl_shard1_replica2 address:http://127.0.0.1:53430 collection:delLiveColl shard:shard1
[junit4] 2> 3804593 T16377 oasc.SolrCore.registerSearcher [delLiveColl_shard1_replica2] Registered new searcher Searcher@6cce9284[delLiveColl_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3804598 T16266 oascc.SolrZkClient.makePath makePath: /collections/delLiveColl/leader_elect/shard1/election
[junit4] 2> 3804629 T16266 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 3804636 T16279 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3804639 T16266 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=179997ms
[junit4] 2> 3804640 T16280 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"delLiveColl"} current state version: 2
[junit4] 2> 3804753 T16280 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/delLiveColl/state.json version: 1
[junit4] 2> 3804756 T16360 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804757 T16279 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804756 T16324 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804758 T16360 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2
[junit4] 2> 3804756 T16342 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating...
[junit4] 2> 3804761 T16324 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2
[junit4] 2> 3804761 T16342 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2
[junit4] 2> 3804772 T16279 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2
[junit4] 2> 3804909 T16347 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard1_replica1
[junit4] 2> 3804910 T16347 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl
[junit4] 2> 3804912 T16347 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3804913 T16347 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl
[junit4] 2> 3804914 T16329 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard2_replica1
[junit4] 2> 3804916 T16329 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl
[junit4] 2> 3804918 T16329 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3804919 T16329 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl
[junit4] 2> 3804919 T16347 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 3804921 T16347 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-006/delLiveColl_shard1_replica1/'
[junit4] 2> 3804922 T16329 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 3804923 T16329 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-005/delLiveColl_shard2_replica1/'
[junit4] 2> 3804930 T16310 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard2_replica2
[junit4] 2> 3804931 T16310 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl
[junit4] 2> 3804942 T16310 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3804942 T16310 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl
[junit4] 2> 3804951 T16347 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 3804956 T16310 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 3804957 T16310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-004/delLiveColl_shard2_replica2/'
[junit4] 2> 3804969 T16329 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 3804978 T16329 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 3804986 T16347 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 3804989 T16310 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 3804989 T16329 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 3804998 T16310 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 3804998 T16329 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 3805015 T16347 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 3805015 T16329 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3805029 T16347 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 3805033 T16329 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 3805049 T16347 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3805052 T16310 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 3805051 T16329 oass.IndexSchema.readSchema [delLiveColl_shard2_replica1] Schema name=test
[junit4] 2> 3805075 T16310 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 3805086 T16347 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 3805088 T16310 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3805093 T16347 oass.IndexSchema.readSchema [delLiveColl_shard1_replica1] Schema name=test
[junit4] 2> 3805105 T16310 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 3805113 T16310 oass.IndexSchema.readSchema [delLiveColl_shard2_replica2] Schema name=test
[junit4] 2> 3805348 T16329 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3805353 T16347 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3805358 T16329 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3805359 T16329 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3805366 T16347 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3805368 T16310 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3805370 T16347 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3805387 T16310 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3805393 T16310 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3805443 T16347 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3805458 T16347 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3805464 T16329 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3805465 T16347 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3805474 T16347 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3805477 T16347 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3805481 T16347 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3805495 T16329 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3805496 T16347 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3805500 T16347 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3805503 T16347 oasc.CoreContainer.create Creating SolrCore 'delLiveColl_shard1_replica1' using configuration from collection delLiveColl
[junit4] 2> 3805506 T16347 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3805508 T16347 oasc.SolrCore.<init> [delLiveColl_shard1_replica1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001/tempDir-006/delLiveColl_shard1_replica1/, dataDir=/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 5FCDE2D5604CA5E0-001/tempDir-006/delLiveColl_shard1_replica1/data/
[junit4] 2> 3805508 T16329 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3805513 T16329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3805514 T16329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3805515 T16329 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3805518 T16329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3805519 T16329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3805520 T16329 oasc.CoreContainer.create Creating SolrCore 'delLiveColl_shard2_replica1' using configuration from collection delLiveColl
[junit4] 2> 3805520 T16329 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3805521 T16329 oasc.SolrCore.<init> [delLiveColl_shard2_replica1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001
[...truncated too long message...]
OSX/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteReplicaTest 5FCDE2D5604CA5E0-001
[junit4] 2> 33658 T16250 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=en_MT, timezone=PNT
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=2,threads=1,free=99152360,total=312143872
[junit4] 2> NOTE: All tests run in this JVM: [DateMathParserTest, TestSerializedLuceneMatchVersion, TestDynamicFieldResource, SOLR749Test, FastVectorHighlighterTest, UUIDFieldTest, TestSolrXmlPersistor, TestXIncludeConfig, DistributedDebugComponentTest, TestSolrDeletionPolicy2, SolrCmdDistributorTest, OutputWriterTest, DistributedQueryElevationComponentTest, ChaosMonkeySafeLeaderTest, TestCloudSchemaless, CoreAdminCreateDiscoverTest, TestConfigSets, TermVectorComponentDistributedTest, OpenExchangeRatesOrgProviderTest, TermsComponentTest, TestBulkSchemaConcurrent, StatsComponentTest, TestTrie, StatelessScriptUpdateProcessorFactoryTest, CoreAdminRequestStatusTest, ReplicationFactorTest, HdfsRecoveryZkTest, TestRawResponseWriter, TestBM25SimilarityFactory, TestQuerySenderListener, BasicZkTest, TestShortCircuitedRequests, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestClassNameShortening, TestPseudoReturnFields, TestExpandComponent, ConnectionManagerTest, UpdateParamsTest, TestSolrDynamicMBean, TestRecoveryHdfs, TestManagedResourceStorage, TestStandardQParsers, OpenCloseCoreStressTest, TestSimpleQParserPlugin, TestCollationField, OutOfBoxZkACLAndCredentialsProvidersTest, RecoveryZkTest, TestNoOpRegenerator, TestConfigOverlay, OverseerCollectionProcessorTest, TestCloudPivotFacet, PreAnalyzedUpdateProcessorTest, DeleteShardTest, URLClassifyProcessorTest, TestManagedSchemaFieldTypeResource, SuggesterFSTTest, TestDocSet, TestElisionMultitermQuery, TestLMJelinekMercerSimilarityFactory, TestShardHandlerFactory, SimplePostToolTest, TestFreeTextSuggestions, ZkCLITest, PolyFieldTest, TestDistributedMissingSort, TestCoreDiscovery, TestJmxMonitoredMap, ZkNodePropsTest, SimpleMLTQParserTest, TestCloudManagedSchemaConcurrent, TestSolrConfigHandler, TestInfoStreamLogging, QueryElevationComponentTest, DistributedQueryComponentOptimizationTest, TestSchemaNameResource, CoreMergeIndexesAdminHandlerTest, DocExpirationUpdateProcessorFactoryTest, TestConfigReload, QueryEqualityTest, FacetPivotSmallTest, TestInitQParser, TestCodecSupport, LeaderElectionIntegrationTest, TestOmitPositions, TestRealTimeGet, TestSearchPerf, TestLRUCache, HdfsBasicDistributedZk2Test, TestSort, DeleteInactiveReplicaTest, MinimalSchemaTest, DirectSolrConnectionTest, DocValuesMultiTest, TestFiltering, TestBinaryField, ConvertedLegacyTest, HdfsWriteToMultipleCollectionsTest, SpatialRPTFieldTypeTest, AnalysisAfterCoreReloadTest, PluginInfoTest, TestDefaultStatsCache, DocumentBuilderTest, TestExtendedDismaxParser, TestSolr4Spatial, OverriddenZkACLAndCredentialsProvidersTest, TestStressVersions, FullSolrCloudDistribCmdsTest, TestStressRecovery, DOMUtilTest, TestAtomicUpdateErrorCases, RankQueryTest, TestSolrIndexConfig, DocValuesTest, TestOverriddenPrefixQueryForCustomFieldType, LeaderFailoverAfterPartitionTest, SolrInfoMBeanTest, FieldMutatingUpdateProcessorTest, TestCursorMarkWithoutUniqueKey, TestNRTOpen, SolrRequestParserTest, RemoteQueryErrorTest, ParsingFieldUpdateProcessorsTest, TestRequestStatusCollectionAPI, ReturnFieldsTest, TestLFUCache, SortByFunctionTest, SolrXmlInZkTest, TestDynamicLoading, EnumFieldTest, TestFieldCollectionResource, TestTolerantSearch, CopyFieldTest, TestComplexPhraseQParserPlugin, BadComponentTest, TestStressLucene, TestIBSimilarityFactory, TestHighlightDedupGrouping, ResponseLogComponentTest, TestUniqueKeyFieldResource, IndexBasedSpellCheckerTest, HighlighterTest, PeerSyncTest, NotRequiredUniqueKeyTest, TestStressUserVersions, LeaderElectionTest, BasicFunctionalityTest, TestReversedWildcardFilterFactory, TestSuggestSpellingConverter, TestSolrDeletionPolicy1, TestCharFilters, TestFieldTypeCollectionResource, CircularListTest, TestInitParams, TestImplicitCoreProperties, TestCustomSort, TestHashPartitioner, TestManagedStopFilterFactory, TestChildDocTransformer, TestQueryUtils, AutoCommitTest, SimpleFacetsTest, TestIntervalFaceting, TestRestManager, AssignTest, SSLMigrationTest, CoreAdminHandlerTest, BadIndexSchemaTest, HighlighterMaxOffsetTest, SuggesterTSTTest, LegacyHTMLStripCharFilterTest, LoggingHandlerTest, TestCSVResponseWriter, TestSearcherReuse, TestJmxIntegration, SolrCoreCheckLockOnStartupTest, TestSolrConfigHandlerCloud, TestRecovery, TestCoreContainer, TestDistributedGrouping, RegexBoostProcessorTest, DistributedFacetPivotLongTailTest, TestSolrQueryParser, RollingRestartTest, TestRandomDVFaceting, TestBlobHandler, TestBadConfig, SliceStateTest, AddSchemaFieldsUpdateProcessorFactoryTest, BasicDistributedZk2Test, CursorPagingTest, TestFileDictionaryLookup, SampleTest, SoftAutoCommitTest, TestSolrQueryParserResource, TestFastLRUCache, DocumentAnalysisRequestHandlerTest, BadCopyFieldTest, CloudMLTQParserTest, TestSystemIdResolver, TestPartialUpdateDeduplication, TestUtils, QueryResultKeyTest, TestPerFieldSimilarity, SuggesterWFSTTest, TestSchemaVersionResource, TestSurroundQueryParser, ChaosMonkeyNothingIsSafeTest, SpatialFilterTest, TestManagedSchemaFieldResource, TestQuerySenderNoQuery, JSONWriterTest, DistributedQueueTest, SharedFSAutoReplicaFailoverUtilsTest, CloudExitableDirectoryReaderTest, ClusterStateTest, TestReload, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest, ShardRoutingTest, TestReplicationHandler, ClusterStateUpdateTest, TestRandomFaceting, ZkSolrClientTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch, TestFaceting, DistributedSpellCheckComponentTest, ZkControllerTest, TestStressReorder, TestJoin, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, SolrCoreTest, SpellCheckComponentTest, TestGroupingSearch, TestFunctionQuery, TestLazyCores, DirectUpdateHandlerTest, TestIndexSearcher, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, TestFoldingMultitermQuery, SuggesterTest, TestCSVLoader, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, TestRemoteStreaming, DistanceFunctionTest, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, IndexSchemaTest, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestBinaryResponseWriter, HighlighterConfigTest, AlternateDirectoryTest, SolrIndexConfigTest, TestMergePolicyConfig, MultiTermTest, NumericFieldsTest, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, PreAnalyzedFieldTest, PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest, TestSolrJ, TestDocumentBuilder, SystemInfoHandlerTest, FileUtilsTest, TestRTGBase, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest, CustomCollectionTest, DeleteReplicaTest]
[junit4] Completed in 34.45s, 1 test, 1 failure <<< FAILURES!
[...truncated 329 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:187: 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: 460 suites, 1869 tests, 1 failure, 52 ignored (21 assumptions)
Total time: 126 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any