You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/02/17 18:05:07 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2004
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2004/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.DeleteReplicaTest.deleteLiveReplicaTest
Error Message:
Should have had a good message here
Stack Trace:
java.lang.AssertionError: Should have had a good message here
at __randomizedtesting.SeedInfo.seed([6BA08CA1BF6BABDA:C6C038AAA25403AF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.DeleteReplicaTest.deleteLiveReplicaTest(DeleteReplicaTest.java:120)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.ShardSplitTest.test
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:49517/rr_l/vy
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:49517/rr_l/vy
at __randomizedtesting.SeedInfo.seed([6BA08CA1BF6BABDA:E3F4B37B1197C622]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at org.apache.solr.cloud.ShardSplitTest.splitShard(ShardSplitTest.java:496)
at org.apache.solr.cloud.ShardSplitTest.incompleteOrOverlappingCustomRangeTest(ShardSplitTest.java:131)
at org.apache.solr.cloud.ShardSplitTest.test(ShardSplitTest.java:83)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:150)
at java.net.SocketInputStream.read(SocketInputStream.java:121)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464)
... 47 more
Build Log:
[...truncated 8950 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/init-core-data-001
[junit4] 2> 252614 T1663 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /rr_l/vy
[junit4] 2> 252621 T1663 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 252622 T1664 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 252723 T1663 oasc.ZkTestServer.run start zk server on port:49507
[junit4] 2> 252724 T1663 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 252725 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 252730 T1671 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11babd9 name:ZooKeeperConnection Watcher:127.0.0.1:49507 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 252731 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 252731 T1663 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 252731 T1663 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 252739 T1663 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 252739 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 252743 T1674 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@92a0dcd name:ZooKeeperConnection Watcher:127.0.0.1:49507/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 252743 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 252743 T1663 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 252744 T1663 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 252749 T1663 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 252754 T1663 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 252758 T1663 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 252773 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 252774 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 252780 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 252781 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 252785 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 252786 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 252796 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 252796 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 252800 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 252801 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 252809 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 252809 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 252844 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 252844 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 252848 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 252849 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 252852 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 252853 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 252857 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 252857 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 252863 T1663 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 252863 T1663 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 254187 T1663 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/cores/collection1
[junit4] 2> 254190 T1663 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 254194 T1663 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@71dd4514{/rr_l/vy,null,AVAILABLE}
[junit4] 2> 254218 T1663 oejs.AbstractConnector.doStart Started ServerConnector@768b8785{HTTP/1.1}{127.0.0.1:49510}
[junit4] 2> 254219 T1663 oejs.Server.doStart Started @256907ms
[junit4] 2> 254219 T1663 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 254220 T1663 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 254220 T1663 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001
[junit4] 2> 254220 T1663 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/'
[junit4] 2> 254261 T1663 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/solr.xml
[junit4] 2> 254270 T1663 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/cores
[junit4] 2> 254271 T1663 oasc.CoreContainer.<init> New CoreContainer 10501390
[junit4] 2> 254271 T1663 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/]
[junit4] 2> 254271 T1663 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 254272 T1663 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 254272 T1663 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 254272 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 254272 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 254273 T1663 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 254273 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 254273 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 254273 T1663 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 254273 T1663 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 254274 T1663 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 254275 T1663 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 254275 T1663 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 254275 T1663 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 254276 T1663 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 254276 T1663 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49507/solr
[junit4] 2> 254276 T1663 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 254276 T1663 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 254278 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 254284 T1688 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a8a1d0d name:ZooKeeperConnection Watcher:127.0.0.1:49507 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 254284 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 254284 T1663 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 254289 T1665 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14b983a903a0002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 254290 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 254293 T1691 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@78010b4e name:ZooKeeperConnection Watcher:127.0.0.1:49507/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 254294 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 254297 T1663 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 254301 T1663 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 254310 T1663 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 254314 T1663 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 254319 T1663 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 254324 T1663 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 254326 T1663 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49510_rr_l%2Fvy
[junit4] 2> 254327 T1663 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49510_rr_l%2Fvy
[junit4] 2> 254331 T1663 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 254335 T1663 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 254338 T1663 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 254341 T1663 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:49510_rr_l%2Fvy
[junit4] 2> 254341 T1663 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 254345 T1663 oasc.Overseer.start Overseer (id=93335594586472451-127.0.0.1:49510_rr_l%2Fvy-n_0000000000) starting
[junit4] 2> 254351 T1663 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 254368 T1663 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 254369 T1693 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 254373 T1692 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 254375 T1663 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 254378 T1663 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 254380 T1663 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 254389 T1663 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/cores
[junit4] 2> 254390 T1663 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/cores/collection1/
[junit4] 2> 254391 T1663 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 254392 T1695 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 254392 T1695 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 254393 T1695 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 254393 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 254405 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49510/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49510_rr_l%2Fvy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 254407 T1692 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49510/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49510_rr_l%2Fvy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 254407 T1692 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 254407 T1692 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 254409 T1691 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> 255395 T1695 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 255395 T1695 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 255396 T1695 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 255397 T1695 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 255399 T1695 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 255399 T1695 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/cores/collection1/'
[junit4] 2> 255417 T1695 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 255421 T1695 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 255429 T1695 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 255438 T1695 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 255451 T1695 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 255453 T1695 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 255457 T1695 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 255585 T1695 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 255585 T1695 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 255587 T1695 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 255591 T1695 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 255631 T1695 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 255631 T1695 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 255632 T1695 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/control/data/
[junit4] 2> 255632 T1695 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@72847a3c
[junit4] 2> 255633 T1695 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/control/data
[junit4] 2> 255633 T1695 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/control/data/index/
[junit4] 2> 255633 T1695 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 255633 T1695 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/control/data/index
[junit4] 2> 255634 T1695 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=75.5419921875, floorSegmentMB=0.267578125, forceMergeDeletesPctAllowed=28.511588018880808, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7155412499128387
[junit4] 2> 255635 T1695 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d77a1b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11499da0),segFN=segments_1,generation=1}
[junit4] 2> 255636 T1695 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 255639 T1695 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 255639 T1695 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 255639 T1695 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 255639 T1695 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 255640 T1695 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 255641 T1695 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 255641 T1695 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 255641 T1695 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 255642 T1695 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 255643 T1695 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 255643 T1695 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 255643 T1695 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 255644 T1695 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 255644 T1695 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 255644 T1695 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 255645 T1695 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 255646 T1695 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 255646 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 255647 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 255647 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 255648 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 255648 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 255648 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 255649 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 255649 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 255650 T1695 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 255650 T1695 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 255658 T1695 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 255659 T1695 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 255660 T1695 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 255661 T1695 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 255662 T1695 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 255665 T1695 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 255666 T1695 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 255666 T1695 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=66.3662109375, floorSegmentMB=1.98828125, forceMergeDeletesPctAllowed=15.111459234154296, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3994565171500365
[junit4] 2> 255667 T1695 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d77a1b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11499da0),segFN=segments_1,generation=1}
[junit4] 2> 255667 T1695 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 255667 T1695 oass.SolrIndexSearcher.<init> Opening Searcher@2fa5660c[collection1] main
[junit4] 2> 255668 T1695 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 255670 T1695 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 255670 T1695 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 255671 T1695 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 255671 T1695 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 255671 T1695 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 255673 T1695 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 255673 T1695 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 255673 T1695 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 255673 T1695 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 255674 T1695 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 255674 T1696 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2fa5660c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 255675 T1699 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49510/rr_l/vy collection:control_collection shard:shard1
[junit4] 2> 255676 T1663 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 255676 T1663 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 255677 T1699 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 255677 T1663 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 255679 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 255685 T1702 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e0bf56 name:ZooKeeperConnection Watcher:127.0.0.1:49507/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 255686 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 255686 T1663 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 255689 T1663 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 255693 T1699 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 255696 T1663 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 255696 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 255699 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 255707 T1699 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 255708 T1699 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C584 name=collection1 org.apache.solr.core.SolrCore@615955c5 url=http://127.0.0.1:49510/rr_l/vy/collection1 node=127.0.0.1:49510_rr_l%2Fvy C584_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:49510/rr_l/vy, node_name=127.0.0.1:49510_rr_l%2Fvy, state=down}
[junit4] 2> 255708 T1699 C584 P49510 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49510/rr_l/vy/collection1/
[junit4] 2> 255711 T1699 C584 P49510 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 255711 T1699 C584 P49510 oasc.SyncStrategy.syncToMe http://127.0.0.1:49510/rr_l/vy/collection1/ has no replicas
[junit4] 2> 255711 T1699 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49510/rr_l/vy/collection1/ shard1
[junit4] 2> 255711 T1699 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 255735 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 255737 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:49510/rr_l/vy",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 255848 T1702 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> 255849 T1691 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> 255896 T1699 oasc.ZkController.register We are http://127.0.0.1:49510/rr_l/vy/collection1/ and leader is http://127.0.0.1:49510/rr_l/vy/collection1/
[junit4] 2> 255896 T1699 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49510/rr_l/vy
[junit4] 2> 255896 T1699 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 255896 T1699 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 255897 T1699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 255899 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 255904 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49510/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49510_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 255906 T1692 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49510/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49510_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 256014 T1691 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> 256015 T1702 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> 257037 T1663 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/cores/collection1
[junit4] 2> 257039 T1663 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 257041 T1663 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 257045 T1663 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@3e706cc6{/rr_l/vy,null,AVAILABLE}
[junit4] 2> 257046 T1663 oejs.AbstractConnector.doStart Started ServerConnector@659aba82{HTTP/1.1}{127.0.0.1:49514}
[junit4] 2> 257046 T1663 oejs.Server.doStart Started @259733ms
[junit4] 2> 257048 T1663 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 257048 T1663 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 257048 T1663 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001
[junit4] 2> 257048 T1663 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/'
[junit4] 2> 257080 T1663 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/solr.xml
[junit4] 2> 257097 T1663 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/cores
[junit4] 2> 257097 T1663 oasc.CoreContainer.<init> New CoreContainer 1976496250
[junit4] 2> 257097 T1663 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/]
[junit4] 2> 257098 T1663 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 257098 T1663 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 257098 T1663 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 257098 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 257099 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 257099 T1663 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 257099 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 257099 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 257099 T1663 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 257100 T1663 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 257100 T1663 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 257101 T1663 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 257101 T1663 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 257101 T1663 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 257102 T1663 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 257102 T1663 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49507/solr
[junit4] 2> 257102 T1663 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 257102 T1663 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 257104 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 257108 T1716 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63ab70a name:ZooKeeperConnection Watcher:127.0.0.1:49507 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 257109 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 257109 T1663 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 257114 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 257117 T1719 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18265c76 name:ZooKeeperConnection Watcher:127.0.0.1:49507/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 257118 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 257132 T1663 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 258143 T1663 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49514_rr_l%2Fvy
[junit4] 2> 258144 T1663 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49514_rr_l%2Fvy
[junit4] 2> 258152 T1663 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 258157 T1663 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/cores
[junit4] 2> 258159 T1663 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/cores/collection1/
[junit4] 2> 258159 T1663 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 258160 T1720 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 258160 T1720 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 258162 T1720 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 258163 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 258164 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49514/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49514_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 258165 T1692 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49514/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49514_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 258165 T1692 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 258165 T1692 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 258167 T1702 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> 258168 T1719 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> 258170 T1691 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> 259166 T1720 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 259166 T1720 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 259167 T1720 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 259168 T1720 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 259170 T1720 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 259170 T1720 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/cores/collection1/'
[junit4] 2> 259186 T1720 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 259191 T1720 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 259201 T1720 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 259208 T1720 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 259223 T1720 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 259226 T1720 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 259231 T1720 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 259346 T1720 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 259348 T1720 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 259348 T1720 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 259353 T1720 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 259394 T1720 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 259395 T1720 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 259395 T1720 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty1/
[junit4] 2> 259395 T1720 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@72847a3c
[junit4] 2> 259396 T1720 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty1
[junit4] 2> 259396 T1720 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty1/index/
[junit4] 2> 259396 T1720 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 259396 T1720 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty1/index
[junit4] 2> 259397 T1720 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=75.5419921875, floorSegmentMB=0.267578125, forceMergeDeletesPctAllowed=28.511588018880808, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7155412499128387
[junit4] 2> 259397 T1720 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9fb35d9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c64e49d),segFN=segments_1,generation=1}
[junit4] 2> 259398 T1720 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 259402 T1720 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 259402 T1720 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 259402 T1720 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 259402 T1720 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 259403 T1720 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 259403 T1720 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 259403 T1720 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 259404 T1720 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 259404 T1720 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 259404 T1720 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 259405 T1720 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 259406 T1720 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 259406 T1720 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 259407 T1720 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 259407 T1720 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 259408 T1720 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 259409 T1720 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 259413 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 259414 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 259414 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 259415 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 259415 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 259416 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 259416 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 259417 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 259417 T1720 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 259418 T1720 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 259426 T1720 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 259427 T1720 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 259428 T1720 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 259429 T1720 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 259430 T1720 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 259431 T1720 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 259432 T1720 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 259433 T1720 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=66.3662109375, floorSegmentMB=1.98828125, forceMergeDeletesPctAllowed=15.111459234154296, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3994565171500365
[junit4] 2> 259434 T1720 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9fb35d9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c64e49d),segFN=segments_1,generation=1}
[junit4] 2> 259434 T1720 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 259434 T1720 oass.SolrIndexSearcher.<init> Opening Searcher@60d25493[collection1] main
[junit4] 2> 259434 T1720 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 259436 T1720 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 259436 T1720 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 259437 T1720 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 259437 T1720 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 259437 T1720 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 259438 T1720 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 259438 T1720 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 259438 T1720 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 259439 T1720 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 259439 T1721 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60d25493[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 259440 T1720 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 259442 T1724 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49514/rr_l/vy collection:collection1 shard:shard2
[junit4] 2> 259443 T1663 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 259443 T1663 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 259447 T1724 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 259459 T1724 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 259463 T1724 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 259463 T1724 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C585 name=collection1 org.apache.solr.core.SolrCore@36f3c3ea url=http://127.0.0.1:49514/rr_l/vy/collection1 node=127.0.0.1:49514_rr_l%2Fvy C585_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:49514/rr_l/vy, node_name=127.0.0.1:49514_rr_l%2Fvy, state=down}
[junit4] 2> 259464 T1724 C585 P49514 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49514/rr_l/vy/collection1/
[junit4] 2> 259464 T1724 C585 P49514 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 259464 T1724 C585 P49514 oasc.SyncStrategy.syncToMe http://127.0.0.1:49514/rr_l/vy/collection1/ has no replicas
[junit4] 2> 259464 T1724 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49514/rr_l/vy/collection1/ shard2
[junit4] 2> 259464 T1724 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 259465 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 259469 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 259483 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 259486 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:49514/rr_l/vy",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 259594 T1691 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> 259594 T1702 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> 259594 T1719 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> 259640 T1724 oasc.ZkController.register We are http://127.0.0.1:49514/rr_l/vy/collection1/ and leader is http://127.0.0.1:49514/rr_l/vy/collection1/
[junit4] 2> 259640 T1724 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49514/rr_l/vy
[junit4] 2> 259640 T1724 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 259640 T1724 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 259641 T1724 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 259643 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 259647 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49514/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49514_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 259649 T1692 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49514/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49514_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 259757 T1691 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> 259757 T1702 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> 259757 T1719 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> 260812 T1663 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/cores/collection1
[junit4] 2> 260814 T1663 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 260816 T1663 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 260820 T1663 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@5e44462e{/rr_l/vy,null,AVAILABLE}
[junit4] 2> 260824 T1663 oejs.AbstractConnector.doStart Started ServerConnector@2a478d0{HTTP/1.1}{127.0.0.1:49517}
[junit4] 2> 260825 T1663 oejs.Server.doStart Started @263509ms
[junit4] 2> 260825 T1663 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 260825 T1663 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 260826 T1663 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001
[junit4] 2> 260826 T1663 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/'
[junit4] 2> 260866 T1663 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/solr.xml
[junit4] 2> 260876 T1663 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/cores
[junit4] 2> 260877 T1663 oasc.CoreContainer.<init> New CoreContainer 2142708390
[junit4] 2> 260877 T1663 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/]
[junit4] 2> 260878 T1663 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 260879 T1663 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 260879 T1663 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 260879 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 260880 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 260880 T1663 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 260880 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 260880 T1663 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 260881 T1663 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 260881 T1663 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 260881 T1663 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 260882 T1663 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 260882 T1663 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 260882 T1663 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 260883 T1663 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 260883 T1663 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49507/solr
[junit4] 2> 260883 T1663 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 260883 T1663 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 260885 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 260893 T1738 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3697b8ac name:ZooKeeperConnection Watcher:127.0.0.1:49507 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 260893 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 260894 T1663 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 260898 T1663 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 260903 T1741 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@48447c59 name:ZooKeeperConnection Watcher:127.0.0.1:49507/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 260903 T1663 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 260916 T1663 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 261925 T1663 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49517_rr_l%2Fvy
[junit4] 2> 261927 T1663 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49517_rr_l%2Fvy
[junit4] 2> 261935 T1663 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 261939 T1663 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/cores
[junit4] 2> 261940 T1663 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/cores/collection1/
[junit4] 2> 261940 T1663 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 261942 T1742 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 261942 T1742 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 261944 T1691 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 261945 T1692 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49517/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49517_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 261946 T1692 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49517/rr_l/vy",
[junit4] 2> "node_name":"127.0.0.1:49517_rr_l%2Fvy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 261946 T1692 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 261947 T1692 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 261949 T1691 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> 261950 T1702 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> 261953 T1742 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 261954 T1719 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> 261955 T1741 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> 262955 T1742 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 262956 T1742 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 262957 T1742 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 262957 T1742 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 262959 T1742 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 262960 T1742 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/cores/collection1/'
[junit4] 2> 262980 T1742 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 262986 T1742 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 262998 T1742 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 263002 T1742 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 263024 T1742 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 263026 T1742 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 263032 T1742 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 263177 T1742 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 263180 T1742 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 263181 T1742 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 263186 T1742 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 263235 T1742 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 263236 T1742 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 263236 T1742 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/shard-2-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty2/
[junit4] 2> 263236 T1742 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@72847a3c
[junit4] 2> 263237 T1742 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty2
[junit4] 2> 263237 T1742 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty2/index/
[junit4] 2> 263237 T1742 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 263238 T1742 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 6BA08CA1BF6BABDA-001/tempDir-001/jetty2/index
[junit4] 2> 263238 T1742 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=75.5419921875, floorSegmentMB=0.267578125, forceMergeDeletesPctAllowed=28.511588018880808, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7155412499128387
[junit4] 2> 263239 T1742 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a5e5642 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a30e4e3),segFN=segments_1,generation=1}
[junit4] 2> 263240 T1742 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 263244 T1742 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 263245 T1742 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 263246 T1742 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 263246 T1742 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 263246 T1742 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 263246 T1742 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 263247 T1742 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 263247 T1742 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 263247 T1742 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 263248 T1742 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 263249 T1742 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 263249 T1742 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 263250 T1742 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 263250 T1742 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 263259 T1742 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 263260 T1742 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 263261 T1742 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 263261 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 263264 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 263264 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 263266 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 263266 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 263267 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 263267 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 263268 T1742 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[j
[...truncated too long message...]
2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ar_MA, timezone=Pacific/Kosrae
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_31 (64-bit)/cpus=2,threads=1,free=92461824,total=285626368
[junit4] 2> NOTE: All tests run in this JVM: [HighlighterMaxOffsetTest, TestExactStatsCache, ExternalCollectionsTest, TestReplicationHandler, DocumentAnalysisRequestHandlerTest, TestSearchPerf, TestJmxMonitoredMap, SharedFSAutoReplicaFailoverUtilsTest, NumericFieldsTest, TestBulkSchemaConcurrent, DistributedFacetPivotLongTailTest, UpdateParamsTest, TestManagedResource, TestMaxScoreQueryParser, RecoveryZkTest, TestFunctionQuery, SuggesterWFSTTest, DirectSolrSpellCheckerTest, RequiredFieldsTest, ShardSplitTest, TestWriterPerf, TestNRTOpen, TestConfig, TestCollationFieldDocValues, TestAtomicUpdateErrorCases, PingRequestHandlerTest, RecoveryAfterSoftCommitTest, QueryEqualityTest, BadIndexSchemaTest, SolrPluginUtilsTest, AnalyticsMergeStrategyTest, RAMDirectoryFactoryTest, CoreAdminCreateDiscoverTest, TestInfoStreamLogging, BasicDistributedZkTest, SchemaVersionSpecificBehaviorTest, TestDynamicFieldCollectionResource, ChaosMonkeyNothingIsSafeTest, TestMiniSolrCloudClusterSSL, TestSolrCoreProperties, TestArbitraryIndexDir, DirectUpdateHandlerOptimizeTest, HdfsRecoveryZkTest, TestManagedSynonymFilterFactory, SynonymTokenizerTest, HdfsSyncSliceTest, ZkControllerTest, SearchHandlerTest, XsltUpdateRequestHandlerTest, SuggesterFSTTest, SolrCoreCheckLockOnStartupTest, TestInitQParser, TestCSVLoader, TestConfigSets, HttpPartitionTest, TestHighlightDedupGrouping, TestPartialUpdateDeduplication, TestDocSet, TestMissingGroups, TestUpdate, ReturnFieldsTest, QueryElevationComponentTest, TestSchemaManager, TestShardHandlerFactory, TestLMJelinekMercerSimilarityFactory, SolrInfoMBeanTest, TestComponentsName, MBeansHandlerTest, BasicZkTest, FieldAnalysisRequestHandlerTest, ParsingFieldUpdateProcessorsTest, TestSolrQueryParser, TestCustomSort, MultiTermTest, FastVectorHighlighterTest, SharedFSAutoReplicaFailoverTest, TestCollectionAPI, ExitableDirectoryReaderTest, SSLMigrationTest, TestReplicaProperties, OutOfBoxZkACLAndCredentialsProvidersTest, TestConfigOverlay, TestOmitPositions, SpellPossibilityIteratorTest, InfoHandlerTest, DocValuesTest, RequestLoggingTest, TestCSVResponseWriter, ActionThrottleTest, CoreAdminRequestStatusTest, LoggingHandlerTest, BlockDirectoryTest, TestQuerySenderListener, TestReplicationHandlerBackup, TestSchemaSimilarityResource, VMParamsZkACLAndCredentialsProvidersTest, TestExceedMaxTermLength, TestRTGBase, TestDFRSimilarityFactory, TestExtendedDismaxParser, TestSystemIdResolver, LeaderElectionTest, TestElisionMultitermQuery, HdfsDirectoryTest, TestClusterStateMutator, TestDocBasedVersionConstraints, SolrRequestParserTest, AtomicUpdatesTest, TestManagedSchemaDynamicFieldResource, TestFiltering, TestFileDictionaryLookup, ExternalFileFieldSortTest, TestExpandComponent, TestBlendedInfixSuggestions, RollingRestartTest, TestInitParams, DistributedQueryComponentOptimizationTest, TestComplexPhraseQParserPlugin, TestDynamicLoading, UUIDFieldTest, TestSolr4Spatial, DateRangeFieldTest, TestNoOpRegenerator, ShowFileRequestHandlerTest, CloudExitableDirectoryReaderTest, TestSweetSpotSimilarityFactory, SolrTestCaseJ4Test, AsyncMigrateRouteKeyTest, TestSolrConfigHandler, EnumFieldTest, TestFieldTypeResource, TestSchemaVersionResource, ScriptEngineTest, CachingDirectoryFactoryTest, CursorPagingTest, TestPivotHelperCode, TestPhraseSuggestions, SimpleCollectionCreateDeleteTest, TestFuzzyAnalyzedSuggestions, TestJmxIntegration, TestBlobHandler, DistanceUnitsTest, QueryResultKeyTest, DistribJoinFromCollectionTest, CoreAdminHandlerTest, StatelessScriptUpdateProcessorFactoryTest, TestManagedSchemaFieldTypeResource, TestSolrDeletionPolicy2, SolrIndexConfigTest, TestDefaultStatsCache, CustomCollectionTest, UUIDUpdateProcessorFallbackTest, TermVectorComponentTest, DefaultValueUpdateProcessorTest, TestOverriddenPrefixQueryForCustomFieldType, SolrXmlInZkTest, TestLFUCache, DistanceFunctionTest, TestRemoteStreaming, IndexBasedSpellCheckerTest, TestFoldingMultitermQuery, TestOrdValues, DistributedTermsComponentTest, TestAnalyzedSuggestions, AddBlockUpdateTest, BadCopyFieldTest, SolrCmdDistributorTest, DocValuesMissingTest, SpatialFilterTest, ZkNodePropsTest, WordBreakSolrSpellCheckerTest, TestCloudPivotFacet, OutputWriterTest, OpenCloseCoreStressTest, TestSolr4Spatial2, TermsComponentTest, TestWordDelimiterFilterFactory, DirectUpdateHandlerTest, CSVRequestHandlerTest, TestPseudoReturnFields, TestFastLRUCache, TestFreeTextSuggestions, TestCloudSchemaless, UniqFieldsUpdateProcessorFactoryTest, TestDistributedSearch, PathHierarchyTokenizerFactoryTest, OverseerTest, TestValueSourceCache, HdfsBasicDistributedZkTest, DistributedIntervalFacetingTest, TestLuceneMatchVersion, FileBasedSpellCheckerTest, TermVectorComponentDistributedTest, FullSolrCloudDistribCmdsTest, DistributedQueryElevationComponentTest, SignatureUpdateProcessorFactoryTest, TestFieldResource, TestCollationField, AddSchemaFieldsUpdateProcessorFactoryTest, SuggesterTSTTest, SimpleFacetsTest, DistributedSpellCheckComponentTest, AutoCommitTest, ClusterStateUpdateTest, TestSolrJ, TestDistributedGrouping, TestManagedResourceStorage, CurrencyFieldOpenExchangeTest, TestQueryTypes, HdfsWriteToMultipleCollectionsTest, TestReqParamsAPI, TestCoreDiscovery, TestFastOutputStream, ResponseHeaderTest, SuggesterTest, TestSolrXml, JSONWriterTest, MigrateRouteKeyTest, IndexSchemaRuntimeFieldTest, TestSuggestSpellingConverter, TriLevelCompositeIdRoutingTest, TestSolrDynamicMBean, TestDefaultSearchFieldResource, PreAnalyzedUpdateProcessorTest, AnalyticsQueryTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestRandomFaceting, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestFaceting, TestRecovery, TestHashPartitioner, TestRealTimeGet, TestStressReorder, TestJoin, TestReload, TestStressVersions, HardAutoCommitTest, TestRangeQuery, TestCoreContainer, SolrCoreTest, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, PeerSyncTest, ConvertedLegacyTest, TestSort, BasicFunctionalityTest, TestLazyCores, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest, TestStressLucene, TestTrie, PolyFieldTest, NoCacheHeaderTest, FieldMutatingUpdateProcessorTest, SortByFunctionTest, DocValuesMultiTest, TestSolrDeletionPolicy1, DebugComponentTest, CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RegexBoostProcessorTest, IndexSchemaTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, SampleTest, TestBinaryField, MinimalSchemaTest, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestCharFilters, TestCodecSupport, TestXIncludeConfig, EchoParamsTest, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, ResourceLoaderTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PluginInfoTest, DateMathParserTest, PreAnalyzedFieldTest, PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, FileUtilsTest, CircularListTest, DistributedMLTComponentTest, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestTolerantSearch, AliasIntegrationTest, AssignTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteReplicaTest]
[junit4] Completed in 30.89s, 1 test, 1 failure <<< FAILURES!
[...truncated 318 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:509: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1349: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:957: There were test failures: 466 suites, 1861 tests, 1 error, 1 failure, 51 ignored (20 assumptions)
Total time: 111 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any