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/03/05 17:23:13 UTC
[JENKINS] Lucene-Solr-5.x-Linux (64bit/jdk1.8.0_31) - Build # 11759
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11759/
Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.client.solrj.impl.CloudSolrClientTest.test
Error Message:
There should be one document because overwrite=true expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: There should be one document because overwrite=true expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([1CB6EE5E0046C651:94E2D184AEBAABA9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.client.solrj.impl.CloudSolrClientTest.testOverwriteOption(CloudSolrClientTest.java:152)
at org.apache.solr.client.solrj.impl.CloudSolrClientTest.test(CloudSolrClientTest.java:128)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
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 org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11454 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrClientTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/init-core-data-001
[junit4] 2> 37366 T136 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 37366 T136 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /zefu/
[junit4] 2> 37369 T136 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
[junit4] 2> 37386 T136 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 37393 T137 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 37592 T136 oasc.ZkTestServer.run start zk server on port:32971
[junit4] 2> 37610 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 37674 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 37704 T138 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4] 2> 39115 T144 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6556232 name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 39116 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 39117 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 39119 T136 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 39153 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 39153 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 39156 T147 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7ec3382d name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 39156 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 39156 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 39161 T136 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 39164 T136 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 39167 T136 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 39170 T136 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 39173 T136 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 39174 T136 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 39185 T136 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 39186 T136 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 39188 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
[junit4] 2> 39189 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
[junit4] 2> 39189 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
[junit4] 2> 39189 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
[junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
[junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
[junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
[junit4] 2> 39191 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
[junit4] 2> 39197 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 39197 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 39199 T150 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c30dc8 name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 39199 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 39199 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 39277 T136 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1
[junit4] 2> 39279 T136 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 39286 T136 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 39294 T136 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56896
[junit4] 2> 39294 T136 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/tempDir-001/control/data, hostContext=/zefu, hostPort=56896, coreRootDirectory=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores}
[junit4] 2> 39296 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 39296 T136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/'
[junit4] 2> 39308 T136 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/solr.xml
[junit4] 2> 39314 T136 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores
[junit4] 2> 39314 T136 oasc.CoreContainer.<init> New CoreContainer 546424954
[junit4] 2> 39315 T136 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/]
[junit4] 2> 39315 T136 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/lib
[junit4] 2> 39315 T136 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/lib).
[junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 39323 T136 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 39323 T136 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 39324 T136 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 39325 T136 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 39326 T136 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 39326 T136 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 39326 T136 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:32971/solr
[junit4] 2> 39337 T136 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 39337 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 39338 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 39340 T162 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73fd6c13 name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 39340 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 39341 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 39348 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 39351 T165 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e0d5b7a name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 39351 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 39359 T136 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 39366 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 39371 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 39374 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 39377 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 39387 T136 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 39388 T136 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56896_zefu
[junit4] 2> 39391 T136 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56896_zefu
[junit4] 2> 39397 T136 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 39401 T136 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 39404 T136 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 39414 T136 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56896_zefu
[junit4] 2> 39415 T136 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 39418 T136 oasc.Overseer.start Overseer (id=93426317596753924-127.0.0.1:56896_zefu-n_0000000000) starting
[junit4] 2> 39431 T136 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 39460 T136 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 39464 T167 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 39470 T136 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 39471 T166 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 39472 T136 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 39476 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 39489 T136 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 39494 T136 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/
[junit4] 2> 39494 T136 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 39495 T169 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 39496 T169 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 39500 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 39500 T169 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 39503 T166 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:56896/zefu",
[junit4] 2> "node_name":"127.0.0.1:56896_zefu",
[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> 39516 T166 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:56896/zefu",
[junit4] 2> "node_name":"127.0.0.1:56896_zefu",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 39517 T166 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 39525 T166 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 39531 T165 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> 40501 T169 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 40502 T169 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 40503 T169 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 40504 T169 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 40505 T169 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 40506 T169 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/'
[junit4] 2> 40527 T169 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 40532 T169 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 40536 T169 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 40540 T169 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0
[junit4] 2> 40547 T169 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 40548 T169 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 40556 T169 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 40678 T169 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 40680 T169 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 40681 T169 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 40681 T169 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 40684 T169 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 40684 T169 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 40684 T169 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/
[junit4] 2> 40685 T169 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 40685 T169 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data
[junit4] 2> 40685 T169 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/index/
[junit4] 2> 40685 T169 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 40686 T169 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/index
[junit4] 2> 40687 T169 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15f7da5e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46a60a26),segFN=segments_1,generation=1}
[junit4] 2> 40687 T169 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 40688 T169 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 40688 T169 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 40688 T169 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 40693 T169 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 40693 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 40693 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 40695 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 40698 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 40698 T169 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 40698 T169 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
[junit4] 2> 40699 T169 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
[junit4] 2> 40699 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 40700 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 40701 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 40702 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 40703 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 40704 T169 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 40704 T169 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 40705 T169 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 40705 T169 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 40706 T169 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15f7da5e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46a60a26),segFN=segments_1,generation=1}
[junit4] 2> 40706 T169 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 40706 T169 oass.SolrIndexSearcher.<init> Opening Searcher@5a0d6df9[collection1] main
[junit4] 2> 40707 T169 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 40708 T169 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 40709 T169 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 40711 T169 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 40711 T169 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 40711 T169 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 40712 T169 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 40712 T169 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 40712 T169 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 40713 T169 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
[junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore
[junit4] 2> 40715 T169 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 40730 T170 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5a0d6df9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 40773 T173 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56896/zefu collection:control_collection shard:shard1
[junit4] 2> 40773 T136 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 40773 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 40782 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 40805 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 40797 T173 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 40815 T176 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c9d1904 name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 40815 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 40816 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 40818 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 40826 T173 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 40828 T136 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 40830 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 40840 T173 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 40841 T173 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 40842 T166 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> ASYNC NEW_CORE C25 name=collection1 org.apache.solr.core.SolrCore@22f1ca80 url=https://127.0.0.1:56896/zefu/collection1 node=127.0.0.1:56896_zefu C25_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:56896/zefu, node_name=127.0.0.1:56896_zefu, state=down}
[junit4] 2> 40842 T173 C25 P56896 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56896/zefu/collection1/
[junit4] 2> 40843 T173 C25 P56896 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 40843 T173 C25 P56896 oasc.SyncStrategy.syncToMe https://127.0.0.1:56896/zefu/collection1/ has no replicas
[junit4] 2> 40843 T173 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56896/zefu/collection1/ shard1
[junit4] 2> 40846 T173 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 40852 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 40853 T166 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:56896/zefu",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 40931 T136 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1
[junit4] 2> 40932 T136 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001
[junit4] 2> 40932 T136 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 40940 T136 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 40943 T136 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:49103
[junit4] 2> 40943 T136 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/zefu, hostPort=49103, coreRootDirectory=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores}
[junit4] 2> 40945 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 40945 T136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/'
[junit4] 2> 40960 T176 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> 40960 T165 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> 40964 T136 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/solr.xml
[junit4] 2> 40975 T136 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores
[junit4] 2> 40976 T136 oasc.CoreContainer.<init> New CoreContainer 2042870102
[junit4] 2> 40976 T136 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/]
[junit4] 2> 40976 T136 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/lib
[junit4] 2> 40977 T136 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/lib).
[junit4] 2> 40985 T136 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 40986 T136 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 40986 T136 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 40986 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 40988 T136 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 40988 T136 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 40988 T136 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 40990 T136 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 40992 T136 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 40992 T136 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 40992 T136 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 40992 T136 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:32971/solr
[junit4] 2> 40993 T136 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 40993 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 40993 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 41000 T188 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32b64f2a name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41001 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 41001 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 41007 T173 oasc.ZkController.register We are https://127.0.0.1:56896/zefu/collection1/ and leader is https://127.0.0.1:56896/zefu/collection1/
[junit4] 2> 41007 T173 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56896/zefu
[junit4] 2> 41007 T173 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 41007 T173 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 41007 T173 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 41009 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 41017 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 41020 T166 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:56896/zefu",
[junit4] 2> "node_name":"127.0.0.1:56896_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 41021 T166 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:56896/zefu",
[junit4] 2> "node_name":"127.0.0.1:56896_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 41019 T191 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74ec733a name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41024 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 41030 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 41125 T176 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> 41126 T191 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> 41125 T165 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> 42034 T136 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49103_zefu
[junit4] 2> 42035 T136 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49103_zefu
[junit4] 2> 42039 T136 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 42045 T136 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 42048 T136 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/
[junit4] 2> 42048 T136 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 42062 T192 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 42062 T192 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 42064 T192 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 42064 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 42069 T166 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:49103/zefu",
[junit4] 2> "node_name":"127.0.0.1:49103_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 42071 T166 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:49103/zefu",
[junit4] 2> "node_name":"127.0.0.1:49103_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 42071 T166 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 42071 T166 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 42182 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 42182 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 42182 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43064 T192 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 43065 T192 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 43066 T192 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 43066 T192 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 43067 T192 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 43067 T192 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/'
[junit4] 2> 43081 T192 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 43083 T192 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 43108 T192 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 43117 T192 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0
[junit4] 2> 43127 T192 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 43128 T192 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 43133 T192 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 43227 T192 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 43229 T192 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 43231 T192 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 43232 T192 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 43234 T192 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 43234 T192 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 43235 T192 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/
[junit4] 2> 43235 T192 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 43237 T192 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data
[junit4] 2> 43237 T192 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 43237 T192 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 43273 T192 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 43276 T192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@264700bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b276c4d),segFN=segments_1,generation=1}
[junit4] 2> 43276 T192 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 43277 T192 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 43277 T192 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 43277 T192 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 43280 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 43280 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 43280 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 43282 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 43282 T192 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 43282 T192 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
[junit4] 2> 43282 T192 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
[junit4] 2> 43283 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 43285 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 43286 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 43287 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 43288 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 43288 T192 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 43300 T192 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 43301 T192 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 43301 T192 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 43303 T192 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@264700bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b276c4d),segFN=segments_1,generation=1}
[junit4] 2> 43303 T192 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 43303 T192 oass.SolrIndexSearcher.<init> Opening Searcher@6aa9b27a[collection1] main
[junit4] 2> 43304 T192 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 43305 T192 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 43305 T192 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 43306 T192 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 43307 T192 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 43307 T192 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 43320 T192 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 43320 T192 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 43321 T192 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 43321 T192 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
[junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore
[junit4] 2> 43321 T193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6aa9b27a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 43322 T192 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 43322 T196 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:49103/zefu collection:collection1 shard:shard2
[junit4] 2> 43323 T136 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 43323 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 43325 T196 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 43336 T196 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 43337 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 43338 T196 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 43338 T196 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C26 name=collection1 org.apache.solr.core.SolrCore@4e7bae5c url=https://127.0.0.1:49103/zefu/collection1 node=127.0.0.1:49103_zefu C26_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:49103/zefu, node_name=127.0.0.1:49103_zefu, state=down}
[junit4] 2> 43338 T196 C26 P49103 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:49103/zefu/collection1/
[junit4] 2> 43339 T196 C26 P49103 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 43339 T166 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> 43339 T196 C26 P49103 oasc.SyncStrategy.syncToMe https://127.0.0.1:49103/zefu/collection1/ has no replicas
[junit4] 2> 43339 T196 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:49103/zefu/collection1/ shard2
[junit4] 2> 43339 T196 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 43346 T166 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:49103/zefu",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 43349 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 43350 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43350 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43350 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43397 T196 oasc.ZkController.register We are https://127.0.0.1:49103/zefu/collection1/ and leader is https://127.0.0.1:49103/zefu/collection1/
[junit4] 2> 43397 T196 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:49103/zefu
[junit4] 2> 43397 T196 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 43398 T196 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 43398 T196 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 43399 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 43405 T166 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:49103/zefu",
[junit4] 2> "node_name":"127.0.0.1:49103_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 43406 T166 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:49103/zefu",
[junit4] 2> "node_name":"127.0.0.1:49103_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 43479 T136 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1
[junit4] 2> 43481 T136 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001
[junit4] 2> 43482 T136 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 43509 T136 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 43525 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43525 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43525 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 43532 T136 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:57049
[junit4] 2> 43533 T136 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/zefu, hostPort=57049, coreRootDirectory=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores}
[junit4] 2> 43541 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 43541 T136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/'
[junit4] 2> 43576 T136 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/solr.xml
[junit4] 2> 43589 T136 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores
[junit4] 2> 43589 T136 oasc.CoreContainer.<init> New CoreContainer 1538888955
[junit4] 2> 43590 T136 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/]
[junit4] 2> 43590 T136 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/lib
[junit4] 2> 43590 T136 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/lib).
[junit4] 2> 43608 T136 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 43610 T136 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 43610 T136 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 43610 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 43611 T136 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 43611 T136 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 43611 T136 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 43615 T136 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 43618 T136 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 43619 T136 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 43619 T136 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 43619 T136 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:32971/solr
[junit4] 2> 43620 T136 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 43620 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 43662 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 43676 T208 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ed42b07 name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 43688 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 43689 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 43736 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 43744 T211 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3634f314 name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 43745 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 43766 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 44772 T136 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57049_zefu
[junit4] 2> 44773 T136 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57049_zefu
[junit4] 2> 44777 T136 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 44779 T136 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1, collection=collection1, absoluteInstDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 44780 T136 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/
[junit4] 2> 44780 T136 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 44781 T212 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 44782 T212 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 44783 T212 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 44783 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 44784 T166 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:57049/zefu",
[junit4] 2> "node_name":"127.0.0.1:57049_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 44785 T166 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:57049/zefu",
[junit4] 2> "node_name":"127.0.0.1:57049_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 44785 T166 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 44785 T166 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 44889 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 44889 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 44889 T211 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 44889 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 45783 T212 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 45783 T212 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 45784 T212 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 45784 T212 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 45785 T212 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 45785 T212 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/'
[junit4] 2> 45794 T212 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 45796 T212 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 45799 T212 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 45802 T212 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0
[junit4] 2> 45810 T212 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 45811 T212 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 45818 T212 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 45905 T212 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 45906 T212 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 45908 T212 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 45908 T212 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 45909 T212 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 45909 T212 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 45909 T212 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/
[junit4] 2> 45909 T212 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 45910 T212 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data
[junit4] 2> 45910 T212 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/index/
[junit4] 2> 45910 T212 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 45911 T212 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/index
[junit4] 2> 45912 T212 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@695ef033 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5203c6ca),segFN=segments_1,generation=1}
[junit4] 2> 45912 T212 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 45913 T212 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 45913 T212 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 45913 T212 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 45913 T212 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 45915 T212 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 45915 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 45915 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 45917 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 45917 T212 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 45917 T212 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
[junit4] 2> 45917 T212 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
[junit4] 2> 45917 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 45919 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 45919 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 45920 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 45921 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 45921 T212 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 45922 T212 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 45922 T212 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 45922 T212 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 45923 T212 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@695ef033 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5203c6ca),segFN=segments_1,generation=1}
[junit4] 2> 45923 T212 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 45923 T212 oass.SolrIndexSearcher.<init> Opening Searcher@7d349155[collection1] main
[junit4] 2> 45923 T212 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 45924 T212 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 45924 T212 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 45925 T212 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 45925 T212 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 45925 T212 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 45925 T212 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 45926 T212 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 45926 T212 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 45926 T212 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
[junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore
[junit4] 2> 45926 T213 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d349155[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 45926 T212 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 45927 T216 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:57049/zefu collection:collection1 shard:shard1
[junit4] 2> 45928 T136 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 45928 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 45928 T216 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 45932 T216 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 45934 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 45934 T216 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 45934 T216 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@799c048e url=https://127.0.0.1:57049/zefu/collection1 node=127.0.0.1:57049_zefu C27_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:57049/zefu, node_name=127.0.0.1:57049_zefu, state=down}
[junit4] 2> 45934 T216 C27 P57049 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:57049/zefu/collection1/
[junit4] 2> 45934 T166 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4]
[...truncated too long message...]
junit4] 2> "base_url":"https://127.0.0.1:42261/zefu",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 113787 T613 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 14
[junit4] 2> 113787 T600 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 1)
[junit4] 2> 113788 T600 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 15
[junit4] 2> 114500 T607 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 114523 T136 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/zefu,null}
[junit4] 2> 114712 T136 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 42261
[junit4] 2> 114712 T136 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=820972659
[junit4] 2> 114712 T136 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 114712 T136 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 114713 T600 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 114713 T136 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@63aa23ab
[junit4] 2> 114714 T136 oasc.ZkController.unRegisterConfListener a listener was removed because of core close
[junit4] 2> 114714 T136 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 114715 T136 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 114715 T136 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 114715 T136 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 114715 T613 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42261/zefu",
[junit4] 2> "node_name":"127.0.0.1:42261_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 114716 T136 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 114716 T613 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42261/zefu",
[junit4] 2> "node_name":"127.0.0.1:42261_zefu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 114716 T136 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data/index;done=false>>]
[junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data/index
[junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data;done=false>>]
[junit4] 2> 114717 T613 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 15
[junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data
[junit4] 2> 114718 T136 oasc.Overseer.close Overseer (id=93426321682399244-127.0.0.1:42261_zefu-n_0000000003) closing
[junit4] 2> 114718 T613 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:42261_zefu
[junit4] 2> 114724 T600 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 1)
[junit4] 2> 116225 T588 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 116225 T600 oascc.ZkStateReader$7.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 116248 T136 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/zefu,null}
[junit4] 2> 116439 T136 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 44903
[junit4] 2> 116439 T136 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56730 56730
[junit4] 2> 117029 T491 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56730 56730
[junit4] 2> 117030 T491 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 117032 T136 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001
[junit4] 2> 79672 T135 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=MockRandom), id=Lucene50(blocksize=128), text=FSTOrd50, range_facet_l=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom)}, docValues:{}, sim=DefaultSimilarity, locale=hu_HU, timezone=America/Argentina/Ushuaia
[junit4] 2> NOTE: Linux 3.13.0-46-generic amd64/Oracle Corporation 1.8.0_31 (64-bit)/cpus=8,threads=1,free=264638336,total=331350016
[junit4] 2> NOTE: All tests run in this JVM: [TestSpellCheckResponse, BasicHttpSolrClientTest, CloudSolrClientMultiConstructorTest, DocumentAnalysisResponseTest, TestEmbeddedSolrServer, TestCoreAdmin, NamedListTest, ConcurrentUpdateSolrClientTest, SolrExampleXMLTest, TestHash, TestXMLEscaping, TermsResponseTest, ModifiableSolrParamsTest, CloudSolrClientTest]
[junit4] Completed on J0 in 80.21s, 3 tests, 1 failure <<< FAILURES!
[...truncated 13 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:204: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:509: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1370: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:976: There were test failures: 54 suites, 342 tests, 1 failure
Total time: 94 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Re: [JENKINS] Lucene-Solr-5.x-Linux (64bit/jdk1.8.0_31) - Build # 11759 - Failure!
Posted by Alan Woodward <al...@flax.co.uk>.
This isn't reproducing for me - does it fail for anybody else?
Alan Woodward
www.flax.co.uk
On 5 Mar 2015, at 16:23, Policeman Jenkins Server wrote:
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11759/
> Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
>
> 1 tests failed.
> FAILED: org.apache.solr.client.solrj.impl.CloudSolrClientTest.test
>
> Error Message:
> There should be one document because overwrite=true expected:<1> but was:<0>
>
> Stack Trace:
> java.lang.AssertionError: There should be one document because overwrite=true expected:<1> but was:<0>
> at __randomizedtesting.SeedInfo.seed([1CB6EE5E0046C651:94E2D184AEBAABA9]:0)
> at org.junit.Assert.fail(Assert.java:93)
> at org.junit.Assert.failNotEquals(Assert.java:647)
> at org.junit.Assert.assertEquals(Assert.java:128)
> at org.junit.Assert.assertEquals(Assert.java:472)
> at org.apache.solr.client.solrj.impl.CloudSolrClientTest.testOverwriteOption(CloudSolrClientTest.java:152)
> at org.apache.solr.client.solrj.impl.CloudSolrClientTest.test(CloudSolrClientTest.java:128)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:483)
> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
> at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
> at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
> 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 org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
> at java.lang.Thread.run(Thread.java:745)
>
>
>
>
> Build Log:
> [...truncated 11454 lines...]
> [junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrClientTest
> [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/init-core-data-001
> [junit4] 2> 37366 T136 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
> [junit4] 2> 37366 T136 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /zefu/
> [junit4] 2> 37369 T136 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
> [junit4] 2> 37386 T136 oasc.ZkTestServer.run STARTING ZK TEST SERVER
> [junit4] 1> client port:0.0.0.0/0.0.0.0:0
> [junit4] 2> 37393 T137 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
> [junit4] 2> 37592 T136 oasc.ZkTestServer.run start zk server on port:32971
> [junit4] 2> 37610 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 37674 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 37704 T138 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
> [junit4] 2> 39115 T144 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6556232 name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 39116 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 39117 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 39119 T136 oascc.SolrZkClient.makePath makePath: /solr
> [junit4] 2> 39153 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 39153 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 39156 T147 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7ec3382d name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 39156 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 39156 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 39161 T136 oascc.SolrZkClient.makePath makePath: /collections/collection1
> [junit4] 2> 39164 T136 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
> [junit4] 2> 39167 T136 oascc.SolrZkClient.makePath makePath: /collections/control_collection
> [junit4] 2> 39170 T136 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
> [junit4] 2> 39173 T136 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
> [junit4] 2> 39174 T136 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
> [junit4] 2> 39185 T136 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
> [junit4] 2> 39186 T136 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
> [junit4] 2> 39188 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
> [junit4] 2> 39189 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
> [junit4] 2> 39189 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
> [junit4] 2> 39189 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
> [junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
> [junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
> [junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
> [junit4] 2> 39190 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
> [junit4] 2> 39191 T136 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
> [junit4] 2> 39197 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 39197 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 39199 T150 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c30dc8 name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 39199 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 39199 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 39277 T136 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1
> [junit4] 2> 39279 T136 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 39286 T136 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
> [junit4] 2> 39294 T136 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56896
> [junit4] 2> 39294 T136 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/tempDir-001/control/data, hostContext=/zefu, hostPort=56896, coreRootDirectory=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores}
> [junit4] 2> 39296 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@73d16e93
> [junit4] 2> 39296 T136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/'
> [junit4] 2> 39308 T136 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/solr.xml
> [junit4] 2> 39314 T136 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores
> [junit4] 2> 39314 T136 oasc.CoreContainer.<init> New CoreContainer 546424954
> [junit4] 2> 39315 T136 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/]
> [junit4] 2> 39315 T136 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/lib
> [junit4] 2> 39315 T136 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/lib).
> [junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 39321 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
> [junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 39322 T136 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 39323 T136 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 39323 T136 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
> [junit4] 2> 39324 T136 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
> [junit4] 2> 39325 T136 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 39326 T136 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 39326 T136 oasc.CoreContainer.load Node Name: 127.0.0.1
> [junit4] 2> 39326 T136 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:32971/solr
> [junit4] 2> 39337 T136 oasc.ZkController.checkChrootPath zkHost includes chroot
> [junit4] 2> 39337 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 39338 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 39340 T162 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73fd6c13 name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 39340 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 39341 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 39348 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 39351 T165 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e0d5b7a name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 39351 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 39359 T136 oascc.SolrZkClient.makePath makePath: /overseer/queue
> [junit4] 2> 39366 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
> [junit4] 2> 39371 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
> [junit4] 2> 39374 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
> [junit4] 2> 39377 T136 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
> [junit4] 2> 39387 T136 oascc.SolrZkClient.makePath makePath: /live_nodes
> [junit4] 2> 39388 T136 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56896_zefu
> [junit4] 2> 39391 T136 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56896_zefu
> [junit4] 2> 39397 T136 oascc.SolrZkClient.makePath makePath: /overseer_elect
> [junit4] 2> 39401 T136 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
> [junit4] 2> 39404 T136 oasc.Overseer.close Overseer (id=null) closing
> [junit4] 2> 39414 T136 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56896_zefu
> [junit4] 2> 39415 T136 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
> [junit4] 2> 39418 T136 oasc.Overseer.start Overseer (id=93426317596753924-127.0.0.1:56896_zefu-n_0000000000) starting
> [junit4] 2> 39431 T136 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
> [junit4] 2> 39460 T136 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
> [junit4] 2> 39464 T167 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
> [junit4] 2> 39470 T136 oascc.SolrZkClient.makePath makePath: /clusterstate.json
> [junit4] 2> 39471 T166 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
> [junit4] 2> 39472 T136 oascc.SolrZkClient.makePath makePath: /aliases.json
> [junit4] 2> 39476 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
> [junit4] 2> 39489 T136 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores
> [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
> [junit4] 2> 39494 T136 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/
> [junit4] 2> 39494 T136 oasc.CorePropertiesLocator.discover Found 1 core definitions
> [junit4] 2> 39495 T169 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
> [junit4] 2> 39496 T169 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
> [junit4] 2> 39500 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 39500 T169 oasc.ZkController.waitForCoreNodeName look for our core node name
> [junit4] 2> 39503 T166 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:56896/zefu",
> [junit4] 2> "node_name":"127.0.0.1:56896_zefu",
> [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> 39516 T166 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:56896/zefu",
> [junit4] 2> "node_name":"127.0.0.1:56896_zefu",
> [junit4] 2> "numShards":"1",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":null,
> [junit4] 2> "collection":"control_collection",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 39517 T166 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
> [junit4] 2> 39525 T166 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
> [junit4] 2> 39531 T165 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> 40501 T169 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
> [junit4] 2> 40502 T169 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
> [junit4] 2> 40503 T169 oasc.ZkController.createCollectionZkNode Collection zkNode exists
> [junit4] 2> 40504 T169 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
> [junit4] 2> 40505 T169 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
> [junit4] 2> 40506 T169 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/'
> [junit4] 2> 40527 T169 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
> [junit4] 2> 40532 T169 oasc.Config.<init> loaded config solrconfig.xml with version 0
> [junit4] 2> 40536 T169 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
> [junit4] 2> 40540 T169 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0
> [junit4] 2> 40547 T169 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 40548 T169 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
> [junit4] 2> 40556 T169 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 40678 T169 oass.IndexSchema.readSchema default search field in schema is text
> [junit4] 2> 40680 T169 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 40681 T169 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
> [junit4] 2> 40681 T169 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
> [junit4] 2> 40684 T169 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
> [junit4] 2> 40684 T169 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 40684 T169 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/
> [junit4] 2> 40685 T169 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 40685 T169 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data
> [junit4] 2> 40685 T169 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/index/
> [junit4] 2> 40685 T169 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 40686 T169 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/control-001/cores/collection1/data/index
> [junit4] 2> 40687 T169 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15f7da5e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46a60a26),segFN=segments_1,generation=1}
> [junit4] 2> 40687 T169 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 40688 T169 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 40688 T169 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 40688 T169 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
> [junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
> [junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
> [junit4] 2> 40689 T169 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 40693 T169 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4] 2> 40693 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
> [junit4] 2> 40693 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
> [junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
> [junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
> [junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
> [junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
> [junit4] 2> 40694 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
> [junit4] 2> 40695 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
> [junit4] 2> 40698 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
> [junit4] 2> 40698 T169 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 40698 T169 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
> [junit4] 2> 40699 T169 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
> [junit4] 2> 40699 T169 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4] 2> 40700 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 40701 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 40702 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 40703 T169 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 40704 T169 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
> [junit4] 2> 40704 T169 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
> [junit4] 2> 40705 T169 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 40705 T169 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 40706 T169 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15f7da5e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46a60a26),segFN=segments_1,generation=1}
> [junit4] 2> 40706 T169 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 40706 T169 oass.SolrIndexSearcher.<init> Opening Searcher@5a0d6df9[collection1] main
> [junit4] 2> 40707 T169 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
> [junit4] 2> 40708 T169 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
> [junit4] 2> 40709 T169 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
> [junit4] 2> 40711 T169 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
> [junit4] 2> 40711 T169 oasr.RestManager.init Initializing RestManager with initArgs: {}
> [junit4] 2> 40711 T169 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
> [junit4] 2> 40712 T169 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
> [junit4] 2> 40712 T169 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
> [junit4] 2> 40712 T169 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 40713 T169 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
> [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore
> [junit4] 2> 40715 T169 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 40730 T170 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5a0d6df9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 40773 T173 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56896/zefu collection:control_collection shard:shard1
> [junit4] 2> 40773 T136 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
> [junit4] 2> 40773 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> 40782 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 40805 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 40797 T173 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
> [junit4] 2> 40815 T176 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c9d1904 name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 40815 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 40816 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 40818 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
> [junit4] 2> 40826 T173 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
> [junit4] 2> 40828 T136 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
> [junit4] 2> 40830 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 40840 T173 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
> [junit4] 2> 40841 T173 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
> [junit4] 2> 40842 T166 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
> [junit4] 2> "operation":"leader",
> [junit4] 2> "shard":"shard1",
> [junit4] 2> "collection":"control_collection"} current state version: 1
> [junit4] 2> ASYNC NEW_CORE C25 name=collection1 org.apache.solr.core.SolrCore@22f1ca80 url=https://127.0.0.1:56896/zefu/collection1 node=127.0.0.1:56896_zefu C25_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:56896/zefu, node_name=127.0.0.1:56896_zefu, state=down}
> [junit4] 2> 40842 T173 C25 P56896 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56896/zefu/collection1/
> [junit4] 2> 40843 T173 C25 P56896 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
> [junit4] 2> 40843 T173 C25 P56896 oasc.SyncStrategy.syncToMe https://127.0.0.1:56896/zefu/collection1/ has no replicas
> [junit4] 2> 40843 T173 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56896/zefu/collection1/ shard1
> [junit4] 2> 40846 T173 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
> [junit4] 2> 40852 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 40853 T166 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:56896/zefu",
> [junit4] 2> "core":"collection1",
> [junit4] 2> "state":"active"} current state version: 1
> [junit4] 2> 40931 T136 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1
> [junit4] 2> 40932 T136 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001
> [junit4] 2> 40932 T136 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 40940 T136 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
> [junit4] 2> 40943 T136 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:49103
> [junit4] 2> 40943 T136 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/zefu, hostPort=49103, coreRootDirectory=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores}
> [junit4] 2> 40945 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@73d16e93
> [junit4] 2> 40945 T136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/'
> [junit4] 2> 40960 T176 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> 40960 T165 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> 40964 T136 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/solr.xml
> [junit4] 2> 40975 T136 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores
> [junit4] 2> 40976 T136 oasc.CoreContainer.<init> New CoreContainer 2042870102
> [junit4] 2> 40976 T136 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/]
> [junit4] 2> 40976 T136 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/lib
> [junit4] 2> 40977 T136 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/lib).
> [junit4] 2> 40985 T136 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 40986 T136 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 40986 T136 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 40986 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
> [junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 40987 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 40988 T136 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 40988 T136 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 40988 T136 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
> [junit4] 2> 40990 T136 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
> [junit4] 2> 40992 T136 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 40992 T136 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 40992 T136 oasc.CoreContainer.load Node Name: 127.0.0.1
> [junit4] 2> 40992 T136 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:32971/solr
> [junit4] 2> 40993 T136 oasc.ZkController.checkChrootPath zkHost includes chroot
> [junit4] 2> 40993 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 40993 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 41000 T188 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32b64f2a name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 41001 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 41001 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 41007 T173 oasc.ZkController.register We are https://127.0.0.1:56896/zefu/collection1/ and leader is https://127.0.0.1:56896/zefu/collection1/
> [junit4] 2> 41007 T173 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56896/zefu
> [junit4] 2> 41007 T173 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
> [junit4] 2> 41007 T173 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
> [junit4] 2> 41007 T173 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
> [junit4] 2> 41009 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 41017 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 41020 T166 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:56896/zefu",
> [junit4] 2> "node_name":"127.0.0.1:56896_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"active",
> [junit4] 2> "shard":"shard1",
> [junit4] 2> "collection":"control_collection",
> [junit4] 2> "operation":"state"} current state version: 2
> [junit4] 2> 41021 T166 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:56896/zefu",
> [junit4] 2> "node_name":"127.0.0.1:56896_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"active",
> [junit4] 2> "shard":"shard1",
> [junit4] 2> "collection":"control_collection",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 41019 T191 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74ec733a name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 41024 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 41030 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
> [junit4] 2> 41125 T176 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> 41126 T191 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> 41125 T165 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> 42034 T136 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49103_zefu
> [junit4] 2> 42035 T136 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49103_zefu
> [junit4] 2> 42039 T136 oasc.Overseer.close Overseer (id=null) closing
> [junit4] 2> 42045 T136 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores
> [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
> [junit4] 2> 42048 T136 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/
> [junit4] 2> 42048 T136 oasc.CorePropertiesLocator.discover Found 1 core definitions
> [junit4] 2> 42062 T192 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
> [junit4] 2> 42062 T192 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
> [junit4] 2> 42064 T192 oasc.ZkController.waitForCoreNodeName look for our core node name
> [junit4] 2> 42064 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 42069 T166 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:49103/zefu",
> [junit4] 2> "node_name":"127.0.0.1:49103_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":null,
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"} current state version: 3
> [junit4] 2> 42071 T166 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:49103/zefu",
> [junit4] 2> "node_name":"127.0.0.1:49103_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":null,
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 42071 T166 oasco.ClusterStateMutator.createCollection building a new cName: collection1
> [junit4] 2> 42071 T166 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
> [junit4] 2> 42182 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 42182 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 42182 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43064 T192 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
> [junit4] 2> 43065 T192 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
> [junit4] 2> 43066 T192 oasc.ZkController.createCollectionZkNode Collection zkNode exists
> [junit4] 2> 43066 T192 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
> [junit4] 2> 43067 T192 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
> [junit4] 2> 43067 T192 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/'
> [junit4] 2> 43081 T192 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
> [junit4] 2> 43083 T192 oasc.Config.<init> loaded config solrconfig.xml with version 0
> [junit4] 2> 43108 T192 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
> [junit4] 2> 43117 T192 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0
> [junit4] 2> 43127 T192 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 43128 T192 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
> [junit4] 2> 43133 T192 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 43227 T192 oass.IndexSchema.readSchema default search field in schema is text
> [junit4] 2> 43229 T192 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 43231 T192 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
> [junit4] 2> 43232 T192 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
> [junit4] 2> 43234 T192 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
> [junit4] 2> 43234 T192 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 43235 T192 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/
> [junit4] 2> 43235 T192 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 43237 T192 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data
> [junit4] 2> 43237 T192 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/index/
> [junit4] 2> 43237 T192 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 43273 T192 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-1-001/cores/collection1/data/index
> [junit4] 2> 43276 T192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@264700bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b276c4d),segFN=segments_1,generation=1}
> [junit4] 2> 43276 T192 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 43277 T192 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 43277 T192 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 43277 T192 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
> [junit4] 2> 43278 T192 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
> [junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
> [junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4] 2> 43279 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
> [junit4] 2> 43280 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
> [junit4] 2> 43280 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
> [junit4] 2> 43280 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
> [junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
> [junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
> [junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
> [junit4] 2> 43281 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
> [junit4] 2> 43282 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
> [junit4] 2> 43282 T192 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 43282 T192 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
> [junit4] 2> 43282 T192 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
> [junit4] 2> 43283 T192 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4] 2> 43285 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 43286 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 43287 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 43288 T192 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 43288 T192 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
> [junit4] 2> 43300 T192 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
> [junit4] 2> 43301 T192 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 43301 T192 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 43303 T192 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@264700bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b276c4d),segFN=segments_1,generation=1}
> [junit4] 2> 43303 T192 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 43303 T192 oass.SolrIndexSearcher.<init> Opening Searcher@6aa9b27a[collection1] main
> [junit4] 2> 43304 T192 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
> [junit4] 2> 43305 T192 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
> [junit4] 2> 43305 T192 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
> [junit4] 2> 43306 T192 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
> [junit4] 2> 43307 T192 oasr.RestManager.init Initializing RestManager with initArgs: {}
> [junit4] 2> 43307 T192 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
> [junit4] 2> 43320 T192 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
> [junit4] 2> 43320 T192 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
> [junit4] 2> 43321 T192 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 43321 T192 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
> [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore
> [junit4] 2> 43321 T193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6aa9b27a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 43322 T192 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 43322 T196 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:49103/zefu collection:collection1 shard:shard2
> [junit4] 2> 43323 T136 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
> [junit4] 2> 43323 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> 43325 T196 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
> [junit4] 2> 43336 T196 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
> [junit4] 2> 43337 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 43338 T196 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
> [junit4] 2> 43338 T196 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
> [junit4] 2> ASYNC NEW_CORE C26 name=collection1 org.apache.solr.core.SolrCore@4e7bae5c url=https://127.0.0.1:49103/zefu/collection1 node=127.0.0.1:49103_zefu C26_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:49103/zefu, node_name=127.0.0.1:49103_zefu, state=down}
> [junit4] 2> 43338 T196 C26 P49103 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:49103/zefu/collection1/
> [junit4] 2> 43339 T196 C26 P49103 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
> [junit4] 2> 43339 T166 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> 43339 T196 C26 P49103 oasc.SyncStrategy.syncToMe https://127.0.0.1:49103/zefu/collection1/ has no replicas
> [junit4] 2> 43339 T196 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:49103/zefu/collection1/ shard2
> [junit4] 2> 43339 T196 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
> [junit4] 2> 43346 T166 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:49103/zefu",
> [junit4] 2> "core":"collection1",
> [junit4] 2> "state":"active"} current state version: 4
> [junit4] 2> 43349 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 43350 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43350 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43350 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43397 T196 oasc.ZkController.register We are https://127.0.0.1:49103/zefu/collection1/ and leader is https://127.0.0.1:49103/zefu/collection1/
> [junit4] 2> 43397 T196 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:49103/zefu
> [junit4] 2> 43397 T196 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
> [junit4] 2> 43398 T196 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
> [junit4] 2> 43398 T196 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
> [junit4] 2> 43399 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 43405 T166 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:49103/zefu",
> [junit4] 2> "node_name":"127.0.0.1:49103_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"active",
> [junit4] 2> "shard":"shard2",
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"} current state version: 5
> [junit4] 2> 43406 T166 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:49103/zefu",
> [junit4] 2> "node_name":"127.0.0.1:49103_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"active",
> [junit4] 2> "shard":"shard2",
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 43479 T136 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1
> [junit4] 2> 43481 T136 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001
> [junit4] 2> 43482 T136 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 43509 T136 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
> [junit4] 2> 43525 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43525 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43525 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
> [junit4] 2> 43532 T136 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:57049
> [junit4] 2> 43533 T136 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/zefu, hostPort=57049, coreRootDirectory=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores}
> [junit4] 2> 43541 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@73d16e93
> [junit4] 2> 43541 T136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/'
> [junit4] 2> 43576 T136 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/solr.xml
> [junit4] 2> 43589 T136 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores
> [junit4] 2> 43589 T136 oasc.CoreContainer.<init> New CoreContainer 1538888955
> [junit4] 2> 43590 T136 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/]
> [junit4] 2> 43590 T136 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/lib
> [junit4] 2> 43590 T136 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/lib).
> [junit4] 2> 43608 T136 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 43609 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
> [junit4] 2> 43610 T136 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 43610 T136 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 43610 T136 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 43611 T136 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 43611 T136 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 43611 T136 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
> [junit4] 2> 43615 T136 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
> [junit4] 2> 43618 T136 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 43619 T136 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 43619 T136 oasc.CoreContainer.load Node Name: 127.0.0.1
> [junit4] 2> 43619 T136 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:32971/solr
> [junit4] 2> 43620 T136 oasc.ZkController.checkChrootPath zkHost includes chroot
> [junit4] 2> 43620 T136 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
> [junit4] 2> 43662 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 43676 T208 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ed42b07 name:ZooKeeperConnection Watcher:127.0.0.1:32971 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 43688 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 43689 T136 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
> [junit4] 2> 43736 T136 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
> [junit4] 2> 43744 T211 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3634f314 name:ZooKeeperConnection Watcher:127.0.0.1:32971/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
> [junit4] 2> 43745 T136 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
> [junit4] 2> 43766 T136 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
> [junit4] 2> 44772 T136 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57049_zefu
> [junit4] 2> 44773 T136 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57049_zefu
> [junit4] 2> 44777 T136 oasc.Overseer.close Overseer (id=null) closing
> [junit4] 2> 44779 T136 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores
> [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1, collection=collection1, absoluteInstDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
> [junit4] 2> 44780 T136 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/
> [junit4] 2> 44780 T136 oasc.CorePropertiesLocator.discover Found 1 core definitions
> [junit4] 2> 44781 T212 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
> [junit4] 2> 44782 T212 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
> [junit4] 2> 44783 T212 oasc.ZkController.waitForCoreNodeName look for our core node name
> [junit4] 2> 44783 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 44784 T166 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:57049/zefu",
> [junit4] 2> "node_name":"127.0.0.1:57049_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":null,
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"} current state version: 6
> [junit4] 2> 44785 T166 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:57049/zefu",
> [junit4] 2> "node_name":"127.0.0.1:57049_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":null,
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 44785 T166 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
> [junit4] 2> 44785 T166 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
> [junit4] 2> 44889 T191 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
> [junit4] 2> 44889 T165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
> [junit4] 2> 44889 T211 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
> [junit4] 2> 44889 T176 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
> [junit4] 2> 45783 T212 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
> [junit4] 2> 45783 T212 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
> [junit4] 2> 45784 T212 oasc.ZkController.createCollectionZkNode Collection zkNode exists
> [junit4] 2> 45784 T212 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
> [junit4] 2> 45785 T212 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
> [junit4] 2> 45785 T212 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/'
> [junit4] 2> 45794 T212 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
> [junit4] 2> 45796 T212 oasc.Config.<init> loaded config solrconfig.xml with version 0
> [junit4] 2> 45799 T212 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
> [junit4] 2> 45802 T212 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0
> [junit4] 2> 45810 T212 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 45811 T212 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
> [junit4] 2> 45818 T212 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 45905 T212 oass.IndexSchema.readSchema default search field in schema is text
> [junit4] 2> 45906 T212 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 45908 T212 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
> [junit4] 2> 45908 T212 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
> [junit4] 2> 45909 T212 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
> [junit4] 2> 45909 T212 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 45909 T212 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/
> [junit4] 2> 45909 T212 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 45910 T212 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data
> [junit4] 2> 45910 T212 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/index/
> [junit4] 2> 45910 T212 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 45911 T212 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-2-001/cores/collection1/data/index
> [junit4] 2> 45912 T212 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@695ef033 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5203c6ca),segFN=segments_1,generation=1}
> [junit4] 2> 45912 T212 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 45913 T212 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 45913 T212 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 45913 T212 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 45913 T212 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
> [junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
> [junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
> [junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
> [junit4] 2> 45914 T212 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 45915 T212 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4] 2> 45915 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
> [junit4] 2> 45915 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
> [junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
> [junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
> [junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
> [junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
> [junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
> [junit4] 2> 45916 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
> [junit4] 2> 45917 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
> [junit4] 2> 45917 T212 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 45917 T212 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
> [junit4] 2> 45917 T212 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly.
> [junit4] 2> 45917 T212 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4] 2> 45919 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 45919 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 45920 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 45921 T212 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 45921 T212 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
> [junit4] 2> 45922 T212 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
> [junit4] 2> 45922 T212 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 45922 T212 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 45923 T212 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@695ef033 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5203c6ca),segFN=segments_1,generation=1}
> [junit4] 2> 45923 T212 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 45923 T212 oass.SolrIndexSearcher.<init> Opening Searcher@7d349155[collection1] main
> [junit4] 2> 45923 T212 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
> [junit4] 2> 45924 T212 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
> [junit4] 2> 45924 T212 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
> [junit4] 2> 45925 T212 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
> [junit4] 2> 45925 T212 oasr.RestManager.init Initializing RestManager with initArgs: {}
> [junit4] 2> 45925 T212 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
> [junit4] 2> 45925 T212 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
> [junit4] 2> 45926 T212 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
> [junit4] 2> 45926 T212 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 45926 T212 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
> [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore
> [junit4] 2> 45926 T213 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d349155[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 45926 T212 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 45927 T216 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:57049/zefu collection:collection1 shard:shard1
> [junit4] 2> 45928 T136 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
> [junit4] 2> 45928 T136 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> 45928 T216 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
> [junit4] 2> 45932 T216 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
> [junit4] 2> 45934 T165 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 45934 T216 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
> [junit4] 2> 45934 T216 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
> [junit4] 2> ASYNC NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@799c048e url=https://127.0.0.1:57049/zefu/collection1 node=127.0.0.1:57049_zefu C27_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:57049/zefu, node_name=127.0.0.1:57049_zefu, state=down}
> [junit4] 2> 45934 T216 C27 P57049 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:57049/zefu/collection1/
> [junit4] 2> 45934 T166 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
> [junit4]
>
> [...truncated too long message...]
>
> junit4] 2> "base_url":"https://127.0.0.1:42261/zefu",
> [junit4] 2> "core":"collection1",
> [junit4] 2> "state":"active"} current state version: 5
> [junit4] 2> 113787 T613 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 14
> [junit4] 2> 113787 T600 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 1)
> [junit4] 2> 113788 T600 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 15
> [junit4] 2> 114500 T607 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
> [junit4] 2> 114523 T136 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/zefu,null}
> [junit4] 2> 114712 T136 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 42261
> [junit4] 2> 114712 T136 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=820972659
> [junit4] 2> 114712 T136 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
> [junit4] 2> 114712 T136 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
> [junit4] 2> 114713 T600 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
> [junit4] 2> 114713 T136 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@63aa23ab
> [junit4] 2> 114714 T136 oasc.ZkController.unRegisterConfListener a listener was removed because of core close
> [junit4] 2> 114714 T136 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
> [junit4] 2> 114715 T136 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
> [junit4] 2> 114715 T136 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4] 2> 114715 T136 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
> [junit4] 2> 114715 T613 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
> [junit4] 2> "core":"collection1",
> [junit4] 2> "core_node_name":"core_node3",
> [junit4] 2> "roles":null,
> [junit4] 2> "base_url":"https://127.0.0.1:42261/zefu",
> [junit4] 2> "node_name":"127.0.0.1:42261_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":"shard2",
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"} current state version: 5
> [junit4] 2> 114716 T136 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4] 2> 114716 T613 oasco.ReplicaMutator.updateState Update state numShards=2 message={
> [junit4] 2> "core":"collection1",
> [junit4] 2> "core_node_name":"core_node3",
> [junit4] 2> "roles":null,
> [junit4] 2> "base_url":"https://127.0.0.1:42261/zefu",
> [junit4] 2> "node_name":"127.0.0.1:42261_zefu",
> [junit4] 2> "numShards":"2",
> [junit4] 2> "state":"down",
> [junit4] 2> "shard":"shard2",
> [junit4] 2> "collection":"collection1",
> [junit4] 2> "operation":"state"}
> [junit4] 2> 114716 T136 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
> [junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data/index;done=false>>]
> [junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data/index
> [junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data;done=false>>]
> [junit4] 2> 114717 T613 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 15
> [junit4] 2> 114717 T136 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001/shard-3-003/cores/collection1/data
> [junit4] 2> 114718 T136 oasc.Overseer.close Overseer (id=93426321682399244-127.0.0.1:42261_zefu-n_0000000003) closing
> [junit4] 2> 114718 T613 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:42261_zefu
> [junit4] 2> 114724 T600 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 1)
> [junit4] 2> 116225 T588 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
> [junit4] 2> 116225 T600 oascc.ZkStateReader$7.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
> [junit4] 2> 116248 T136 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/zefu,null}
> [junit4] 2> 116439 T136 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 44903
> [junit4] 2> 116439 T136 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56730 56730
> [junit4] 2> 117029 T491 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56730 56730
> [junit4] 2> 117030 T491 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
> [junit4] 2> Maximum concurrent create/delete watches above limit:
> [junit4] 2>
> [junit4] 2> 5 /solr/aliases.json
> [junit4] 2> 5 /solr/clusterstate.json
> [junit4] 2> 4 /solr/configs/conf1
> [junit4] 2> 3 /solr/collections/collection1/state.json
> [junit4] 2>
> [junit4] 2> Maximum concurrent children watches above limit:
> [junit4] 2>
> [junit4] 2> 5 /solr/live_nodes
> [junit4] 2> 4 /solr/overseer/collection-queue-work
> [junit4] 2>
> [junit4] 2> 117032 T136 oas.SolrTestCaseJ4.deleteCore ###deleteCore
> [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.CloudSolrClientTest 1CB6EE5E0046C651-001
> [junit4] 2> 79672 T135 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=MockRandom), id=Lucene50(blocksize=128), text=FSTOrd50, range_facet_l=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom)}, docValues:{}, sim=DefaultSimilarity, locale=hu_HU, timezone=America/Argentina/Ushuaia
> [junit4] 2> NOTE: Linux 3.13.0-46-generic amd64/Oracle Corporation 1.8.0_31 (64-bit)/cpus=8,threads=1,free=264638336,total=331350016
> [junit4] 2> NOTE: All tests run in this JVM: [TestSpellCheckResponse, BasicHttpSolrClientTest, CloudSolrClientMultiConstructorTest, DocumentAnalysisResponseTest, TestEmbeddedSolrServer, TestCoreAdmin, NamedListTest, ConcurrentUpdateSolrClientTest, SolrExampleXMLTest, TestHash, TestXMLEscaping, TermsResponseTest, ModifiableSolrParamsTest, CloudSolrClientTest]
> [junit4] Completed on J0 in 80.21s, 3 tests, 1 failure <<< FAILURES!
>
> [...truncated 13 lines...]
> BUILD FAILED
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:204: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:509: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1370: The following error occurred while executing this line:
> /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:976: There were test failures: 54 suites, 342 tests, 1 failure
>
> Total time: 94 minutes 5 seconds
> Build step 'Invoke Ant' marked build as failure
> [description-setter] Description set: Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
> Archiving artifacts
> Recording test results
> Email was triggered for: Failure - Any
> Sending email for trigger: Failure - Any
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org