You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/09/18 00:43:01 UTC
[JENKINS] Lucene-Solr-Tests-4.x-Java7 - Build # 2114 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-Java7/2114/
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([967544E8FBC042A8:1793CAF08C9F2294]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:161)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
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 12404 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
[junit4] 2> SLF4J: Class path contains multiple SLF4J bindings.
[junit4] 2> SLF4J: Found binding in [jar:file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/test-lib/slf4j-log4j12-1.7.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: Found binding in [jar:file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/lib/slf4j-log4j12-1.7.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[junit4] 2> SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
[junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
[junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/init-core-data-001
[junit4] 2> 4335 T11 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 4450 T11 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 4460 T11 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
[junit4] 2> 4557 T11 oas.SolrTestCaseJ4.setUp ###Starting testShutdown
[junit4] 2> 4568 T11 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4578 T12 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 4778 T11 oasc.ZkTestServer.run start zk server on port:20423
[junit4] 2> 4797 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 5028 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 5073 T13 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4] 2> 7230 T18 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b99d741 name:ZooKeeperConnection Watcher:127.0.0.1:20423 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 7231 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 7232 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 7243 T11 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 7372 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 7374 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 7378 T20 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a33ee9b name:ZooKeeperConnection Watcher:127.0.0.1:20423/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 7379 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 7381 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 7418 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 7426 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 7432 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 7437 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 7445 T11 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 7512 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 7522 T11 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 7525 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 7632 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
[junit4] 2> 7635 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
[junit4] 2> 7637 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
[junit4] 2> 7640 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
[junit4] 2> 7642 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
[junit4] 2> 7644 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
[junit4] 2> 7646 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4] 2> 7648 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
[junit4] 2> 7650 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
[junit4] 2> 8105 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 8107 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 8139 T21 oaz.ClientCnxnSocketNIO.connect ERROR Unable to open socket to ff01::114/ff01:0:0:0:0:0:0:114:33332
[junit4] 2> 8152 T21 oaz.ClientCnxn$SendThread.run WARN Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect java.net.SocketException: Protocol family unavailable
[junit4] 2> at sun.nio.ch.Net.connect0(Native Method)
[junit4] 2> at sun.nio.ch.Net.connect(Net.java:465)
[junit4] 2> at sun.nio.ch.Net.connect(Net.java:457)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:670)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.registerAndConnect(ClientCnxnSocketNIO.java:277)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.connect(ClientCnxnSocketNIO.java:287)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:967)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2>
[junit4] 2> 9258 T21 oaz.ClientCnxnSocketNIO.connect ERROR Unable to open socket to ff01::114/ff01:0:0:0:0:0:0:114:33332
[junit4] 2> 10459 T11 oas.SolrTestCaseJ4.tearDown ###Ending testShutdown
[junit4] 2> 10641 T11 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:20423 20423
[junit4] 2> 10790 T12 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:20423 20423
[junit4] 2> 10862 T11 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 10864 T11 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 10866 T23 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 10965 T11 oasc.ZkTestServer.run start zk server on port:12770
[junit4] 2> 10967 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 10968 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 10972 T29 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63f4ad66 name:ZooKeeperConnection Watcher:127.0.0.1:12770 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 10973 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 10974 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 10974 T11 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 10979 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 10980 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 10982 T31 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@507d1b2 name:ZooKeeperConnection Watcher:127.0.0.1:12770/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 10983 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 10983 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 10984 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 10989 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 10993 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 10997 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 11001 T11 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 11003 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 11009 T11 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 11010 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 11115 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
[junit4] 2> 11117 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
[junit4] 2> 11118 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
[junit4] 2> 11119 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
[junit4] 2> 11121 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
[junit4] 2> 11122 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
[junit4] 2> 11123 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4] 2> 11124 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
[junit4] 2> 11126 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
[junit4] 2> 12861 T11 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 13382 T11 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:53881
[junit4] 2> 13624 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 13647 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 13648 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-003
[junit4] 2> 13648 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-003/'
[junit4] 2> 15249 T11 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-003/solr.xml
[junit4] 2> 15606 T11 oasc.CoreContainer.<init> New CoreContainer 77611740
[junit4] 2> 15613 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-003/]
[junit4] 2> 15853 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 15854 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 15855 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 15855 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 15856 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 15856 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 15857 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 15857 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 15858 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 15998 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 16033 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 16043 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 16059 T11 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 16060 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:12770/solr
[junit4] 2> 16126 T11 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 16127 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 16128 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 16133 T43 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35bcc9ae name:ZooKeeperConnection Watcher:127.0.0.1:12770 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 16133 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 16134 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 16159 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 16161 T45 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fe2b3f name:ZooKeeperConnection Watcher:127.0.0.1:12770/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 16161 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 16187 T11 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 16202 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 16214 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 16218 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 16222 T11 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 16235 T11 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 16237 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53881_
[junit4] 2> 16245 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53881_
[junit4] 2> 16249 T11 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 16252 T11 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 16255 T11 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 16279 T11 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53881_
[junit4] 2> 16280 T11 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 16282 T11 oasc.Overseer.start Overseer (id=92470840760795139-127.0.0.1:53881_-n_0000000000) starting
[junit4] 2> 16316 T11 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 16486 T11 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 17045 T47 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 17046 T11 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 17050 T11 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 17052 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 17211 T46 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 17810 T49 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 17810 T49 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 17826 T49 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 17834 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 17845 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1:53881",
[junit4] 2> "node_name":"127.0.0.1:53881_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 17846 T46 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 17901 T46 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 17922 T50 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> 18828 T49 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 18828 T49 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 18830 T49 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 18831 T49 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 18832 T49 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 18833 T49 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-003/collection1/'
[junit4] 2> 19064 T49 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.11.0
[junit4] 2> 19420 T49 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 19580 T49 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 19725 T49 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 19851 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.IntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 19915 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.LongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 19965 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.FloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20007 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20188 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20246 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20288 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20328 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20360 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.BCDIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20390 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.BCDLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20426 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.BCDStrField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 20722 T49 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 21640 T49 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 21645 T49 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 21669 T49 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 21669 T49 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 21846 T49 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 22162 T49 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 22238 T49 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-003/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/control/data/
[junit4] 2> 22246 T49 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 22395 T49 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/control/data
[junit4] 2> 22396 T49 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/control/data/index/
[junit4] 2> 22397 T49 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 22398 T49 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/control/data/index
[junit4] 2> 22728 T49 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-2c97a223)),segFN=segments_1,generation=1}
[junit4] 2> 22737 T49 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 24692 T49 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 24763 T49 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 24764 T49 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 24764 T49 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 24799 T49 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 24799 T49 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 24804 T49 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 24828 T49 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 24847 T49 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 24853 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 24877 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 25003 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 25027 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 25028 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 25030 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 25162 T49 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 25162 T49 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 25189 T49 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-2c97a223)),segFN=segments_1,generation=1}
[junit4] 2> 25189 T49 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 25310 T49 oass.SolrIndexSearcher.<init> Opening Searcher@23e9d8eb[collection1] main
[junit4] 2> 25405 T49 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 25407 T49 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 25407 T49 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 25436 T49 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 25437 T49 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 25627 T49 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 25628 T49 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 25629 T49 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 25639 T49 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json
[junit4] 2> 25643 T49 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json
[junit4] 2> 25643 T49 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 25644 T49 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 25933 T49 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 25934 T51 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23e9d8eb[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 25947 T54 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53881 collection:control_collection shard:shard1
[junit4] 2> 25948 T11 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1
[junit4] 2> 25949 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 26023 T54 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 26033 T54 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 26036 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 26036 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 26037 T54 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 26037 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 26038 T54 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 26040 T56 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1656d6d7 name:ZooKeeperConnection Watcher:127.0.0.1:12770/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 26040 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 26041 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 26044 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 26053 T11 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 26063 T50 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> 26064 T57 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> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@e7b848e url=http://127.0.0.1:53881/collection1 node=127.0.0.1:53881_ C0_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:53881, node_name=127.0.0.1:53881_, state=down}
[junit4] 2> 26070 T54 C0 P53881 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53881/collection1/
[junit4] 2> 26084 T54 C0 P53881 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 26085 T54 C0 P53881 oasc.SyncStrategy.syncToMe http://127.0.0.1:53881/collection1/ has no replicas
[junit4] 2> 26085 T54 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53881/collection1/ shard1
[junit4] 2> 26111 T54 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 26120 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 26229 T50 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> 26229 T57 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> 26274 T54 oasc.ZkController.register We are http://127.0.0.1:53881/collection1/ and leader is http://127.0.0.1:53881/collection1/
[junit4] 2> 26286 T54 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53881
[junit4] 2> 26286 T54 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 26286 T54 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 26287 T54 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 26289 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 26292 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:53881",
[junit4] 2> "node_name":"127.0.0.1:53881_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "operation":"state"}
[junit4] 2> 26410 T57 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> 26410 T50 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> 26511 T11 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 26513 T11 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 26517 T11 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:63036
[junit4] 2> 26518 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 26519 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 26519 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-004
[junit4] 2> 26520 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-004/'
[junit4] 2> 26585 T11 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-004/solr.xml
[junit4] 2> 26753 T11 oasc.CoreContainer.<init> New CoreContainer 1643919300
[junit4] 2> 26754 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-004/]
[junit4] 2> 26757 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 26757 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 26758 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 26759 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 26760 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 26760 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 26761 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 26762 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 26763 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 26764 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 26765 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 26766 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 26766 T11 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 26767 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:12770/solr
[junit4] 2> 26768 T11 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 26769 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 26770 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 26773 T68 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@75b1571d name:ZooKeeperConnection Watcher:127.0.0.1:12770 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 26774 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 26775 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 26779 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 26781 T70 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54625921 name:ZooKeeperConnection Watcher:127.0.0.1:12770/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 26782 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 26794 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 27802 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63036_
[junit4] 2> 27804 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63036_
[junit4] 2> 27812 T11 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 27832 T72 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 27833 T72 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 27835 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 27835 T72 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 27840 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:63036",
[junit4] 2> "node_name":"127.0.0.1:63036_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 27841 T46 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 27842 T46 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 27951 T71 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> 27951 T57 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> 27951 T50 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> 28836 T72 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 28837 T72 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 28839 T72 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 28839 T72 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 28841 T72 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 28842 T72 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-004/collection1/'
[junit4] 2> 28937 T72 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.11.0
[junit4] 2> 29021 T72 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 29123 T72 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 29250 T72 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 29811 T72 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 29815 T72 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 29820 T72 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 29821 T72 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 29825 T72 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 29825 T72 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 29826 T72 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-004/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty1/
[junit4] 2> 29826 T72 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 29828 T72 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty1
[junit4] 2> 29828 T72 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty1/index/
[junit4] 2> 29829 T72 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 29830 T72 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty1/index
[junit4] 2> 29836 T72 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62872dd9)),segFN=segments_1,generation=1}
[junit4] 2> 29837 T72 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 29841 T72 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 29843 T72 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 29844 T72 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 29844 T72 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 29845 T72 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 29846 T72 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 29846 T72 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 29847 T72 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 29847 T72 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 29848 T72 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 29849 T72 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 29870 T72 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 29873 T72 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 29875 T72 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 29877 T72 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 29882 T72 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 29882 T72 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 29884 T72 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62872dd9)),segFN=segments_1,generation=1}
[junit4] 2> 29885 T72 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 29885 T72 oass.SolrIndexSearcher.<init> Opening Searcher@718360c8[collection1] main
[junit4] 2> 29886 T72 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 29889 T72 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 29889 T72 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 29890 T72 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 29891 T72 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 29892 T72 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 29894 T72 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 29895 T72 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 29895 T72 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 29896 T72 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 29898 T73 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@718360c8[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 29898 T72 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 29900 T76 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:63036 collection:collection1 shard:shard1
[junit4] 2> 29901 T11 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1
[junit4] 2> 29902 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 29902 T76 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 29913 T76 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 29917 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 29918 T76 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 29918 T76 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@16303561 url=http://127.0.0.1:63036/collection1 node=127.0.0.1:63036_ C1_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:63036, node_name=127.0.0.1:63036_, state=down}
[junit4] 2> 29919 T76 C1 P63036 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:63036/collection1/
[junit4] 2> 29920 T76 C1 P63036 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 29920 T76 C1 P63036 oasc.SyncStrategy.syncToMe http://127.0.0.1:63036/collection1/ has no replicas
[junit4] 2> 29921 T76 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:63036/collection1/ shard1
[junit4] 2> 29921 T76 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 29927 T50 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> 29928 T71 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> 29927 T57 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> 29940 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 29943 T50 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> 29943 T71 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> 29943 T57 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> 29984 T76 oasc.ZkController.register We are http://127.0.0.1:63036/collection1/ and leader is http://127.0.0.1:63036/collection1/
[junit4] 2> 29985 T76 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:63036
[junit4] 2> 29985 T76 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 29986 T76 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 29986 T76 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 29989 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 29994 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:63036",
[junit4] 2> "node_name":"127.0.0.1:63036_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "operation":"state"}
[junit4] 2> 30102 T50 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> 30102 T71 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> 30102 T57 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> 30269 T11 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 30271 T11 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 30274 T11 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:38694
[junit4] 2> 30275 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 30276 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 30276 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-005
[junit4] 2> 30277 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-005/'
[junit4] 2> 30338 T11 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-005/solr.xml
[junit4] 2> 30460 T11 oasc.CoreContainer.<init> New CoreContainer 900131310
[junit4] 2> 30461 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-005/]
[junit4] 2> 30463 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 30464 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 30465 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 30465 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 30466 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 30467 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 30467 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 30468 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 30469 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 30470 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 30471 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 30472 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 30472 T11 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 30473 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:12770/solr
[junit4] 2> 30474 T11 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 30474 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 30476 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 30478 T87 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@614823c6 name:ZooKeeperConnection Watcher:127.0.0.1:12770 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 30479 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 30480 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 30484 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 30486 T89 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b2ef3e5 name:ZooKeeperConnection Watcher:127.0.0.1:12770/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 30486 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 30498 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 31505 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38694_
[junit4] 2> 31506 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38694_
[junit4] 2> 31513 T11 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 31524 T91 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 31525 T91 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 31527 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 31527 T91 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 31531 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:38694",
[junit4] 2> "node_name":"127.0.0.1:38694_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 31532 T46 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 31533 T46 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 31640 T50 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> 31641 T57 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> 31641 T90 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> 31641 T71 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> 32528 T91 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 32529 T91 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 32531 T91 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 32531 T91 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 32533 T91 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 32534 T91 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-005/collection1/'
[junit4] 2> 32603 T91 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.11.0
[junit4] 2> 32656 T91 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 32759 T91 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 32868 T91 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 33352 T91 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 33356 T91 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 33360 T91 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 33360 T91 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 33364 T91 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 33365 T91 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 33365 T91 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-005/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty2/
[junit4] 2> 33366 T91 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 33367 T91 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty2
[junit4] 2> 33368 T91 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty2/index/
[junit4] 2> 33368 T91 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 33369 T91 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-002/jetty2/index
[junit4] 2> 33374 T91 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-3bdcdd14)),segFN=segments_1,generation=1}
[junit4] 2> 33374 T91 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 33376 T91 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 33377 T91 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 33378 T91 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 33379 T91 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 33379 T91 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 33380 T91 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 33380 T91 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 33381 T91 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 33382 T91 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 33382 T91 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 33383 T91 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 33400 T91 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 33402 T91 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 33404 T91 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 33406 T91 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 33411 T91 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 33411 T91 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 33413 T91 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-3bdcdd14)),segFN=segments_1,generation=1}
[junit4] 2> 33414 T91 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 33415 T91 oass.SolrIndexSearcher.<init> Opening Searcher@398f600[collection1] main
[junit4] 2> 33415 T91 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 33418 T91 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 33418 T91 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 33419 T91 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 33420 T91 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 33420 T91 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 33422 T91 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 33423 T91 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 33424 T91 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 33424 T91 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 33426 T92 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@398f600[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 33426 T91 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 33428 T95 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:38694 collection:collection1 shard:shard2
[junit4] 2> 33429 T11 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1
[junit4] 2> 33430 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 33431 T95 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 33440 T95 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 33444 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 33444 T95 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 33445 T95 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@5c2e0f4b url=http://127.0.0.1:38694/collection1 node=127.0.0.1:38694_ C2_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:38694, node_name=127.0.0.1:38694_, state=down}
[junit4] 2> 33445 T95 C2 P38694 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:38694/collection1/
[junit4] 2> 33446 T95 C2 P38694 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 33447 T95 C2 P38694 oasc.SyncStrategy.syncToMe http://127.0.0.1:38694/collection1/ has no replicas
[junit4] 2> 33447 T95 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:38694/collection1/ shard2
[junit4] 2> 33448 T95 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 33453 T90 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> 33453 T71 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> 33453 T50 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> 33453 T57 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> 33465 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 33467 T90 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> 33467 T71 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> 33467 T50 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> 33467 T57 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> 33509 T95 oasc.ZkController.register We are http://127.0.0.1:38694/collection1/ and leader is http://127.0.0.1:38694/collection1/
[junit4] 2> 33510 T95 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38694
[junit4] 2> 33510 T95 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 33511 T95 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 33511 T95 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 33514 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 33518 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:38694",
[junit4] 2> "node_name":"127.0.0.1:38694_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "operation":"state"}
[junit4] 2> 33625 T50 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> 33625 T57 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> 33625 T90 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> 33625 T71 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> 33679 T11 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 33680 T11 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 33684 T11 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:61227
[junit4] 2> 33685 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 33685 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 33686 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-006
[junit4] 2> 33687 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-006/'
[junit4] 2> 33737 T11 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-006/solr.xml
[junit4] 2> 33835 T11 oasc.CoreContainer.<init> New CoreContainer 1797232104
[junit4] 2> 33836 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001/tempDir-006/]
[junit4] 2> 33837 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 33838 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 33839 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 33839 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 33840 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 33841 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 33841 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 33842 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 33842 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 33844 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 33845 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 33845 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 33846 T11 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 33847 T11 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:12770/solr
[junit4] 2> 33847 T11 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 33848 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 33849 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 33852 T106 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@66aae949 name:ZooKeeperConnection Watcher:127.0.0.1:12770 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 33852 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 33853 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 33857 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 33859 T108 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6acc0d4e name:ZooKeeperConnection Watcher:127.0.0.1:12770/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 33859 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 33870 T11 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 34876 T11 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61227_
[junit4] 2> 34878 T11 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61227_
[junit4] 2> 34885 T11 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 34895 T110 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 34896 T110 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 34897 T50 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 34897 T110 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 34901 T46 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:61227",
[junit4] 2> "node_name":"127.0.0.1:61227_",
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 34902 T46 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 34903 T46 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 35010 T109 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 35010 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 35010 T50 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 35010 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 35010 T90 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 35898 T110 oasc.ZkController.waitForShardId waiting to find shard id in clusterst
[...truncated too long message...]
tor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:344)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:320)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:89)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:292)
[junit4] 2>
[junit4] 2> 67914 T11 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 72917 T11 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 72919 T11 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:12770 12770
[junit4] 2> 73062 T23 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:12770 12770
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CloudSolrServerTest -Dtests.method=testDistribSearch -Dtests.seed=967544E8FBC042A8 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=is -Dtests.timezone=Africa/Lagos -Dtests.file.encoding=UTF-8
[junit4] FAILURE 62.4s J1 | CloudSolrServerTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([967544E8FBC042A8:1793CAF08C9F2294]:0)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:161)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 73263 T11 oas.SolrTestCaseJ4.setUp ###Starting testWrongZkChrootTest
[junit4] 2> 73267 T11 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 73269 T143 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 73368 T11 oasc.ZkTestServer.run start zk server on port:61545
[junit4] 2> 73369 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 73371 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 73375 T149 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a9fdcbf name:ZooKeeperConnection Watcher:127.0.0.1:61545 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 73375 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 73376 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 73377 T11 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 73381 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 73383 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 73385 T151 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2e1f3792 name:ZooKeeperConnection Watcher:127.0.0.1:61545/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 73386 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 73386 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 73387 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 73391 T11 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 73395 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 73398 T11 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 73402 T11 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 73403 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 73409 T11 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 73411 T11 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 73516 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
[junit4] 2> 73517 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
[junit4] 2> 73518 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
[junit4] 2> 73519 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
[junit4] 2> 73520 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
[junit4] 2> 73521 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
[junit4] 2> 73523 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4] 2> 73524 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
[junit4] 2> 73525 T11 oasc.AbstractZkTestCase.putConfig skipping /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
[junit4] 2> 73527 T11 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 73528 T11 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 73530 T153 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@27492832 name:ZooKeeperConnection Watcher:127.0.0.1:61545/solr/xyz/foo got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 73531 T11 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 73531 T11 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 73533 T11 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 73536 T11 oas.SolrTestCaseJ4.tearDown ###Ending testWrongZkChrootTest
[junit4] 2> 73538 T11 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:61545 61545
[junit4] 2> 74760 T143 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:61545 61545
[junit4] 2> 74768 T11 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.impl.CloudSolrServerTest-967544E8FBC042A8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene410), sim=DefaultSimilarity, locale=is, timezone=Africa/Lagos
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=88945160,total=198705152
[junit4] 2> NOTE: All tests run in this JVM: [CloudSolrServerTest]
[junit4] Completed on J1 in 75.62s, 3 tests, 1 failure <<< FAILURES!
[...truncated 129 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:491: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:471: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:60: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/extra-targets.xml:39: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build.xml:202: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/common-build.xml:494: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/lucene/common-build.xml:1360: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-4.x-Java7/lucene/common-build.xml:963: There were test failures: 53 suites, 313 tests, 1 failure
Total time: 60 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-4.x-Java7 #2113
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 10777092 bytes
Compression is 0.0%
Took 2.5 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure