Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4534/
Java: 32bit/jdk1.8.0_31 -client -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.ReplicationFactorTest.test
Error Message:
org.apache.http.NoHttpResponseException: The target server failed to respond
Stack Trace:
org.apache.solr.client.solrj.SolrServerException:
org.apache.http.NoHttpResponseException: The target server failed to respond
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:901)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:754)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:284)
at
org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:112)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to
respond
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
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:461)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:211)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:207)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:568)
at
org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:565)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
Build Log:
[...truncated 10157 lines...]
[junit4] Suite: org.apache.solr.cloud.ReplicationFactorTest
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\init-core-data-001
[junit4] 2> 4760805 T20711
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /shd/ik
[junit4] 2> 4760818 T20711 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4760819 T20712 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 4760926 T20711 oasc.ZkTestServer.run start zk server on
port:56111
[junit4] 2> 4760926 T20711
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4760929 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4760935 T20719 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1cdba40 name:ZooKeeperConnection
Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 4760937 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4760937 T20711 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4760938 T20711 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 4760943 T20711
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4760945 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4760949 T20722 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@a442fc name:ZooKeeperConnection
Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4760949 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4760949 T20711 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4760949 T20711 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 4760952 T20711 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 4760958 T20711 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 4760962 T20711 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 4760967 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4760967 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 4760973 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 4760973 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 4760984 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4760985 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4760991 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4760992 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 4760996 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4760996 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 4761002 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4761004 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 4761008 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4761009 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 4761013 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4761014 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 4761018 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4761018 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4761023 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4761023 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 4761030 T20711 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4761031 T20711 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 4762829 T20711 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1
[junit4] 2> 4762836 T20711 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 4762841 T20711 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@1dfa03b{/shd/ik,null,AVAILABLE}
[junit4] 2> 4762847 T20711 oejs.AbstractConnector.doStart Started
ServerConnector@115fa68{HTTP/1.1}{127.0.0.1:56119}
[junit4] 2> 4762850 T20711 oejs.Server.doStart Started @4750222ms
[junit4] 2> 4762850 T20711 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\tempDir-001/control/data, hostContext=/shd/ik,
hostPort=56118,
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores}
[junit4] 2> 4762854 T20711 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 4762854 T20711 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\'
[junit4] 2> 4762911 T20711 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\solr.xml
[junit4] 2> 4762920 T20711 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores
[junit4] 2> 4762921 T20711 oasc.CoreContainer.<init> New CoreContainer
24459565
[junit4] 2> 4762921 T20711 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\]
[junit4] 2> 4762921 T20711 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\lib
[junit4] 2> 4762921 T20711 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\lib).
[junit4] 2> 4762938 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4762938 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4762939 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4762939 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4762941 T20711 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4762941 T20711 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4762942 T20711 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4762942 T20711 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 4762942 T20711 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:56111/solr
[junit4] 2> 4762942 T20711 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4762942 T20711
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4762944 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4762947 T20736 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@65c1ca name:ZooKeeperConnection
Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 4762947 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4762948 T20711 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4762952 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4762955 T20739 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@12974a6 name:ZooKeeperConnection
Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4762955 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4762959 T20711 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 4762965 T20711 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 4762970 T20711 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 4762974 T20711 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 4762978 T20711 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 4762984 T20711 oascc.SolrZkClient.makePath makePath:
/live_nodes
[junit4] 2> 4762987 T20711 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56118_shd%2Fik
[junit4] 2> 4762988 T20711 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:56118_shd%2Fik
[junit4] 2> 4762992 T20711 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 4762996 T20711 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 4762999 T20711 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4763001 T20711 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:56118_shd%2Fik
[junit4] 2> 4763002 T20711 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 4763005 T20711 oasc.Overseer.start Overseer
(id=93446827646255107-127.0.0.1:56118_shd%2Fik-n_0000000000) starting
[junit4] 2> 4763010 T20711 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 4763023 T20711 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 4763025 T20740 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 4763025 T20741 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 4763026 T20711 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 4763032 T20711 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 4763036 T20711
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4763046 T20711 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml,
transient=false, schema=schema.xml, loadOnStartup=true,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1,
collection=control_collection,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\, coreNodeName=,
dataDir=data\, shard=}
[junit4] 2> 4763048 T20711 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\
[junit4] 2> 4763049 T20711 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 4763051 T20743 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 4763052 T20743 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4763053 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4763053 T20743 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4763055 T20740 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:56118/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik",
[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> 4763057 T20740 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:56118/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4763058 T20740 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 4763058 T20740 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 4763060 T20739 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> 4764087 T20743 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4764087 T20743 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 4764089 T20743 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4764090 T20743 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 4764092 T20743 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 4764092 T20743 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\'
[junit4] 2> 4764129 T20743 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4764133 T20743 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4764146 T20743 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4764155 T20743 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 4764161 T20743 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4764165 T20743 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4764174 T20743 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 4764316 T20743 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4764322 T20743 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 4764323 T20743 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4764342 T20743 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4764347 T20743 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4764353 T20743 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4764355 T20743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4764365 T20743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4764365 T20743 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4764369 T20743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4764369 T20743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4764369 T20743 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 4764370 T20743 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4764370 T20743 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\data\
[junit4] 2> 4764370 T20743 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1844159
[junit4] 2> 4764371 T20743 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\data\
[junit4] 2> 4764371 T20743 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\data\index/
[junit4] 2> 4764371 T20743 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\data\index' doesn't exist.
Creating new index...
[junit4] 2> 4764372 T20743 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\control-001\cores\collection1\data\index
[junit4] 2> 4764373 T20743 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=95.150390625,
floorSegmentMB=0.251953125, forceMergeDeletesPctAllowed=7.370749593348257,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 4764376 T20743 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@10d7970
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5dbfa6),segFN=segments_1,generation=1}
[junit4] 2> 4764376 T20743 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4764385 T20743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 4764386 T20743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 4764387 T20743 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 4764387 T20743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 4764388 T20743 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 4764388 T20743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4764390 T20743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4764396 T20743 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4764396 T20743 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4764403 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4764406 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4764408 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4764413 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4764423 T20743 oasc.RequestHandlers.initHandlersFromConfig
Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 4764424 T20743 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4764426 T20743 oasu.UpdateHandler.<init> Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4764426 T20743 oasu.UpdateLog.init Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 4764428 T20743 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4764428 T20743 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4764428 T20743 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=7, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=86.7216796875,
floorSegmentMB=2.1728515625, forceMergeDeletesPctAllowed=1.7278524219281666,
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 4764429 T20743 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@10d7970
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5dbfa6),segFN=segments_1,generation=1}
[junit4] 2> 4764429 T20743 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4764430 T20743 oass.SolrIndexSearcher.<init> Opening
Searcher@19b3949[collection1] main
[junit4] 2> 4764430 T20743 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 4764432 T20743 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 4764432 T20743 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4764433 T20743
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4764434 T20743 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4764434 T20743 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4764435 T20743
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4764436 T20743 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4764436 T20743 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4764436 T20743 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4764437 T20743 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4764438 T20744 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@19b3949[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4764440 T20747 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:56118/shd/ik
collection:control_collection shard:shard1
[junit4] 2> 4764441 T20711 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4764441 T20711 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4764442 T20747 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 4764443 T20711
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4764443 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4764446 T20751 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@efb8ee name:ZooKeeperConnection
Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4764447 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4764448 T20711 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4764450 T20711
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4764454 T20747
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 4764458 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4764458 T20747
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4764459 T20747
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C7187 name=collection1
org.apache.solr.core.SolrCore@1535745
url=http://127.0.0.1:56118/shd/ik/collection1 node=127.0.0.1:56118_shd%2Fik
C7187_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:56118/shd/ik, node_name=127.0.0.1:56118_shd%2Fik,
state=down}
[junit4] 2> 4764459 T20747 C7187 P56118 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:56118/shd/ik/collection1/
[junit4] 2> 4764459 T20747 C7187 P56118 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4764459 T20747 C7187 P56118 oasc.SyncStrategy.syncToMe
http://127.0.0.1:56118/shd/ik/collection1/ has no replicas
[junit4] 2> 4764459 T20747
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:56118/shd/ik/collection1/ shard1
[junit4] 2> 4764460 T20747 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 4764461 T20740 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> 4764462 T20711 oasc.ChaosMonkey.monkeyLog monkey: init -
expire sessions:false cause connection loss:false
[junit4] 2> 4764476 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4764480 T20740 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:56118/shd/ik";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 4764585 T20739 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> 4764586 T20751 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> 4764627 T20747 oasc.ZkController.register We are
http://127.0.0.1:56118/shd/ik/collection1/ and leader is
http://127.0.0.1:56118/shd/ik/collection1/
[junit4] 2> 4764627 T20747 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:56118/shd/ik
[junit4] 2> 4764627 T20747 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4764627 T20747 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 4764627 T20747 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4764633 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4764640 T20740 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:56118/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 4764642 T20740 oasco.ReplicaMutator.updateState Update state
numShards=3 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:56118/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4764749 T20739 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> 4764751 T20751 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> 4766097 T20711 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1
[junit4] 2> 4766102 T20711 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1 in directory
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001
[junit4] 2> 4766103 T20711 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 4766106 T20711 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@13f2e05{/shd/ik,null,AVAILABLE}
[junit4] 2> 4766109 T20711 oejs.AbstractConnector.doStart Started
ServerConnector@173f165{HTTP/1.1}{127.0.0.1:56132}
[junit4] 2> 4766109 T20711 oejs.Server.doStart Started @4753565ms
[junit4] 2> 4766110 T20711 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\tempDir-001/jetty1, solrconfig=solrconfig.xml,
hostContext=/shd/ik, hostPort=56131,
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores}
[junit4] 2> 4766110 T20711 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 4766112 T20711 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\'
[junit4] 2> 4766152 T20711 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\solr.xml
[junit4] 2> 4766163 T20711 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores
[junit4] 2> 4766163 T20711 oasc.CoreContainer.<init> New CoreContainer
27072979
[junit4] 2> 4766163 T20711 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\]
[junit4] 2> 4766164 T20711 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\lib
[junit4] 2> 4766164 T20711 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\lib).
[junit4] 2> 4766178 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4766179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4766179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4766180 T20711 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4766180 T20711 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4766182 T20711 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4766182 T20711 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 4766182 T20711 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:56111/solr
[junit4] 2> 4766182 T20711 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4766182 T20711
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4766184 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4766187 T20765 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@16d6064 name:ZooKeeperConnection
Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 4766187 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4766187 T20711 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4766191 T20713 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14bfd65073e0005, 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> 4766192 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4766195 T20768 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@f0e83e name:ZooKeeperConnection
Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4766195 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4766208 T20711
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4767175 T20711 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56131_shd%2Fik
[junit4] 2> 4767177 T20711 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:56131_shd%2Fik
[junit4] 2> 4767184 T20711 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4767190 T20711 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml,
transient=false, schema=schema.xml, loadOnStartup=true,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1, collection=collection1,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\, coreNodeName=,
dataDir=data\, shard=}
[junit4] 2> 4767193 T20711 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\
[junit4] 2> 4767193 T20711 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 4767196 T20769 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 4767197 T20769 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4767198 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4767198 T20769 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4767199 T20740 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:56131/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 4767201 T20740 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56131/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4767201 T20740 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 4767201 T20740 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 4767204 T20751 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> 4767204 T20768 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> 4767204 T20739 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> 4768158 T20769 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4768158 T20769 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 4768160 T20769 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4768160 T20769 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4768162 T20769 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4768162 T20769 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\'
[junit4] 2> 4768190 T20769 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4768195 T20769 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4768208 T20769 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4768216 T20769 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 4768230 T20769 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4768232 T20769 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4768247 T20769 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 4768425 T20769 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4768436 T20769 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 4768436 T20769 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4768462 T20769 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4768467 T20769 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4768472 T20769 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4768474 T20769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4768474 T20769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4768475 T20769 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4768477 T20769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4768477 T20769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4768478 T20769 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 4768478 T20769 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4768479 T20769 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\data\
[junit4] 2> 4768479 T20769 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1844159
[junit4] 2> 4768481 T20769 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\data\
[junit4] 2> 4768481 T20769 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\data\index/
[junit4] 2> 4768481 T20769 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\data\index' doesn't exist.
Creating new index...
[junit4] 2> 4768483 T20769 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-1-001\cores\collection1\data\index
[junit4] 2> 4768484 T20769 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=95.150390625,
floorSegmentMB=0.251953125, forceMergeDeletesPctAllowed=7.370749593348257,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 4768486 T20769 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@171766b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e9574),segFN=segments_1,generation=1}
[junit4] 2> 4768486 T20769 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4768506 T20769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 4768507 T20769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 4768508 T20769 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 4768508 T20769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 4768509 T20769 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 4768509 T20769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4768510 T20769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4768511 T20769 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4768511 T20769 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4768520 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4768524 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4768528 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4768530 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4768544 T20769 oasc.RequestHandlers.initHandlersFromConfig
Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 4768545 T20769 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4768546 T20769 oasu.UpdateHandler.<init> Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4768546 T20769 oasu.UpdateLog.init Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 4768548 T20769 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4768548 T20769 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4768550 T20769 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=7, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=86.7216796875,
floorSegmentMB=2.1728515625, forceMergeDeletesPctAllowed=1.7278524219281666,
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 4768550 T20769 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@171766b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e9574),segFN=segments_1,generation=1}
[junit4] 2> 4768551 T20769 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4768551 T20769 oass.SolrIndexSearcher.<init> Opening
Searcher@11132f9[collection1] main
[junit4] 2> 4768552 T20769 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4768554 T20769 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4768554 T20769 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4768555 T20769
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4768556 T20769 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4768556 T20769 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4768557 T20769
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4768557 T20769 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4768558 T20769 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4768558 T20769 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4768560 T20769 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4768561 T20770 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@11132f9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4768562 T20773 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:56131/shd/ik collection:collection1
shard:shard2
[junit4] 2> 4768563 T20711 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4768564 T20711 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4768565 T20773 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 4768579 T20773
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard2
[junit4] 2> 4768585 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4768586 T20773
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4768586 T20773
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C7188 name=collection1
org.apache.solr.core.SolrCore@342579
url=http://127.0.0.1:56131/shd/ik/collection1 node=127.0.0.1:56131_shd%2Fik
C7188_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:56131/shd/ik, node_name=127.0.0.1:56131_shd%2Fik,
state=down}
[junit4] 2> 4768586 T20773 C7188 P56131 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:56131/shd/ik/collection1/
[junit4] 2> 4768587 T20773 C7188 P56131 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4768587 T20773 C7188 P56131 oasc.SyncStrategy.syncToMe
http://127.0.0.1:56131/shd/ik/collection1/ has no replicas
[junit4] 2> 4768587 T20773
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:56131/shd/ik/collection1/ shard2
[junit4] 2> 4768589 T20773 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 4768592 T20740 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> 4768606 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4768609 T20740 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:56131/shd/ik";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 4768713 T20768 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> 4768714 T20739 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> 4768716 T20751 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> 4768756 T20773 oasc.ZkController.register We are
http://127.0.0.1:56131/shd/ik/collection1/ and leader is
http://127.0.0.1:56131/shd/ik/collection1/
[junit4] 2> 4768756 T20773 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:56131/shd/ik
[junit4] 2> 4768756 T20773 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4768756 T20773 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 4768756 T20773 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4768760 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4768764 T20740 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:56131/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 4768765 T20740 oasco.ReplicaMutator.updateState Update state
numShards=3 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:56131/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4768867 T20751 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> 4768869 T20768 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> 4768870 T20739 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> 4770079 T20711 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1
[junit4] 2> 4770083 T20711 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2 in directory
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001
[junit4] 2> 4770085 T20711 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 4770092 T20711 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@b03e13{/shd/ik,null,AVAILABLE}
[junit4] 2> 4770093 T20711 oejs.AbstractConnector.doStart Started
ServerConnector@15e66c1{HTTP/1.1}{127.0.0.1:56142}
[junit4] 2> 4770094 T20711 oejs.Server.doStart Started @4757759ms
[junit4] 2> 4770094 T20711 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\tempDir-001/jetty2, solrconfig=solrconfig.xml,
hostContext=/shd/ik, hostPort=56141,
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores}
[junit4] 2> 4770094 T20711 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 4770096 T20711 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\'
[junit4] 2> 4770144 T20711 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\solr.xml
[junit4] 2> 4770157 T20711 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores
[junit4] 2> 4770157 T20711 oasc.CoreContainer.<init> New CoreContainer
7964319
[junit4] 2> 4770157 T20711 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\]
[junit4] 2> 4770157 T20711 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\lib
[junit4] 2> 4770157 T20711 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\lib).
[junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4770180 T20711 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4770181 T20711 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4770181 T20711 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4770182 T20711 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4770183 T20711 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 4770183 T20711 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:56111/solr
[junit4] 2> 4770183 T20711 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4770183 T20711
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4770187 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4770189 T20791 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@124d2c2 name:ZooKeeperConnection
Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 4770190 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4770191 T20711 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4770194 T20711 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4770198 T20794 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@a6cb8d name:ZooKeeperConnection
Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4770199 T20711 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4770211 T20711
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4771180 T20711 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56141_shd%2Fik
[junit4] 2> 4771181 T20711 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:56141_shd%2Fik
[junit4] 2> 4771189 T20711 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4771194 T20711 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml,
transient=false, schema=schema.xml, loadOnStartup=true,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1, collection=collection1,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\, coreNodeName=,
dataDir=data\, shard=}
[junit4] 2> 4771194 T20711 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\
[junit4] 2> 4771196 T20711 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 4771197 T20795 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 4771198 T20795 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4771199 T20739 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4771199 T20795 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4771201 T20740 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:56141/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56141_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 4771202 T20740 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:56141/shd/ik";,
[junit4] 2> "node_name":"127.0.0.1:56141_shd%2Fik",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4771203 T20740 oasco.ReplicaMutator.updateState Collection
already exists with numShards=3
[junit4] 2> 4771204 T20740 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard3
[junit4] 2> 4771207 T20751 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> 4771207 T20768 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> 4771208 T20794 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> 4771208 T20739 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> 4772160 T20795 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4772160 T20795 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 4772161 T20795 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4772162 T20795 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4772164 T20795 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4772164 T20795 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\'
[junit4] 2> 4772197 T20795 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4772201 T20795 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4772215 T20795 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4772219 T20795 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 4772229 T20795 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4772231 T20795 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4772245 T20795 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 4772395 T20795 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4772403 T20795 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 4772404 T20795 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4772422 T20795 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4772436 T20795 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4772442 T20795 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4772445 T20795
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4772445 T20795
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4772446 T20795 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4772448 T20795
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4772448 T20795
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4772449 T20795 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 4772449 T20795 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4772450 T20795 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\data\
[junit4] 2> 4772450 T20795 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1844159
[junit4] 2> 4772451 T20795 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\data\
[junit4] 2> 4772452 T20795 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\data\index/
[junit4] 2> 4772452 T20795 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\data\index' doesn't exist.
Creating new index...
[junit4] 2> 4772453 T20795 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
32516BE7A65674CF-001\shard-2-001\cores\collection1\data\index
[junit4] 2> 4772453 T20795 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=95.150390625,
floorSegmentMB=0.251953125, forceMergeDeletesPctAllowed=7.370749593348257,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 4772455 T20795 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@100896b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19163c),segFN=segments_1,generation=1}
[junit4] 2> 4772455 T20795 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4772463 T20795 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 4772464 T20795 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 4772465 T20795 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 4772465 T20795 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 4772466 T20795 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 4772466 T20795 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4772467 T20795 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4772467 T20795 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4772467 T20795 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4772472 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4772477 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4772480 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4772482 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4772495 T20795 oasc.RequestHandlers.initHandlersFromConfig
Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 4772495 T20795 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4772497 T20795 oasu.UpdateHandler.<init> Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4772497 T20795 oasu.UpdateLog.init Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 4772500 T20795 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4772500 T20795 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4772500 T20795 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=7, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=86.7216796875,
floorSegmentMB=2.1728515625, forceMergeDeletesPctAllowed=1.7278524219281666,
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 4772500 T20795 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDir
[...truncated too long message...]
perties, SpatialRPTFieldTypeTest, FullSolrCloudDistribCmdsTest,
SolrIndexSplitterTest, SolrTestCaseJ4Test, TestSolrConfigHandler,
TestJmxMonitoredMap, ExternalCollectionsTest, QueryResultKeyTest,
ResponseHeaderTest, TestFileDictionaryLookup,
DistributedSpellCheckComponentTest, TestCloudPivotFacet, BlockCacheTest,
InfoHandlerTest, TestReplicaProperties, TestSweetSpotSimilarityFactory,
SuggestComponentTest, TestIBSimilarityFactory, TestPostingsSolrHighlighter,
TestCloudInspectUtil, TestPHPSerializedResponseWriter,
UUIDUpdateProcessorFallbackTest, TestRawResponseWriter, TestCharFilters,
TestSolr4Spatial2, DistributedFacetPivotLongTailTest, BasicDistributedZkTest,
TestCodecSupport, DirectSolrConnectionTest, TestReRankQParserPlugin,
SaslZkACLProviderTest, SpellCheckComponentTest, PreAnalyzedUpdateProcessorTest,
TestSystemIdResolver, PrimUtilsTest, TestStressLucene,
UniqFieldsUpdateProcessorFactoryTest, DistributedQueryComponentCustomSortTest,
TestManagedResource, TestWordDelimiterFilterFactory, CacheHeaderTest,
DistribDocExpirationUpdateProcessorTest, ConvertedLegacyTest,
BlockDirectoryTest, AnalysisAfterCoreReloadTest, TestHighlightDedupGrouping,
XmlUpdateRequestHandlerTest, TestCoreContainer, TestManagedResourceStorage,
BadIndexSchemaTest, ClusterStateTest, TestSolrDeletionPolicy1,
TestLeaderElectionZkExpiry, TestDocBasedVersionConstraints,
TestSolrQueryParser, TestBinaryField, TestLuceneMatchVersion, UpdateParamsTest,
TestSolrDynamicMBean, AsyncMigrateRouteKeyTest, TestHashPartitioner,
TestRestManager, SpellPossibilityIteratorTest, SpellingQueryConverterTest,
TestJoin, TestUpdate, TestBM25SimilarityFactory, TestQueryTypes,
TestReversedWildcardFilterFactory, DistributedDebugComponentTest,
CurrencyFieldXmlFileTest, HdfsRecoveryZkTest, TestDefaultSimilarityFactory,
TestSchemaNameResource, ParsingFieldUpdateProcessorsTest, SolrCoreTest,
SSLMigrationTest, TestStandardQParsers, ExitableDirectoryReaderTest,
JsonLoaderTest, OutputWriterTest, SoftAutoCommitTest,
TestDistributedMissingSort, TestImplicitCoreProperties,
BinaryUpdateRequestHandlerTest, TestReqParamsAPI, TestMiniSolrCloudClusterSSL,
DistributedSuggestComponentTest, TestComplexPhraseQParserPlugin,
TestLRUStatsCache, TestStressUserVersions, TestCloudManagedSchemaConcurrent,
TestManagedSchemaFieldResource, SimpleFacetsTest, TestBlendedInfixSuggestions,
DateFieldTest, UUIDFieldTest, TestFoldingMultitermQuery,
LeaderInitiatedRecoveryOnCommitTest, ZkSolrClientTest, SuggesterTSTTest,
TestSchemaResource, TriLevelCompositeIdRoutingTest,
HdfsBasicDistributedZk2Test, SharedFSAutoReplicaFailoverTest,
TestObjectReleaseTracker, TestManagedSchemaDynamicFieldResource,
TestExactStatsCache, LeaderElectionTest, HdfsSyncSliceTest, TestHdfsUpdateLog,
SampleTest, DistribJoinFromCollectionTest, EnumFieldTest, MigrateRouteKeyTest,
VMParamsZkACLAndCredentialsProvidersTest, TestNonDefinedSimilarityFactory,
CloudMLTQParserTest, SolrRequestParserTest, TestFieldSortValues,
CollectionsAPIDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType,
TestConfigReload, TestSolrQueryParserDefaultOperatorResource,
TestStressRecovery, TestIntervalFaceting, TestCoreDiscovery, RecoveryZkTest,
ChangedSchemaMergeTest, TestJmxIntegration, TestAnalyzedSuggestions,
PreAnalyzedFieldTest, AtomicUpdatesTest, TestMergePolicyConfig,
DirectUpdateHandlerTest, TestSearcherReuse, DebugComponentTest, TestRecovery,
FacetPivotSmallTest, TestSchemaVersionResource, DistributedFacetPivotLargeTest,
TestRandomFaceting, CursorPagingTest, TestReloadAndDeleteDocs,
TestJettySolrRunner, TestAnalyzeInfixSuggestions, TestFieldCollectionResource,
TestZkChroot, RAMDirectoryFactoryTest, EchoParamsTest, TestSolrDeletionPolicy2,
SpatialHeatmapFacetsTest, TestPerFieldSimilarity, DistanceFunctionTest,
LukeRequestHandlerTest, SortByFunctionTest,
SignatureUpdateProcessorFactoryTest, TestShardHandlerFactory,
TestDefaultStatsCache, TestFreeTextSuggestions, BasicFunctionalityTest,
TestRebalanceLeaders, RollingRestartTest, SuggesterWFSTTest,
StatelessScriptUpdateProcessorFactoryTest, TestPhraseSuggestions,
TestPseudoReturnFields, TestRandomDVFaceting, DirectSolrSpellCheckerTest,
TestDistributedGrouping, TestManagedSynonymFilterFactory,
TestPartialUpdateDeduplication, RemoteQueryErrorTest, QueryParsingTest,
ResourceLoaderTest, TestDefaultSearchFieldResource, TestFaceting,
RegexBoostProcessorTest, TestIndexingPerformance, ReturnFieldsTest,
AddSchemaFieldsUpdateProcessorFactoryTest, DeleteInactiveReplicaTest,
CurrencyFieldOpenExchangeTest, TestSchemaManager, DisMaxRequestHandlerTest,
PolyFieldTest, AliasIntegrationTest, HdfsChaosMonkeySafeLeaderTest,
OverriddenZkACLAndCredentialsProvidersTest, TestRTGBase, PeerSyncTest,
TestSolrIndexConfig, RequestHandlersTest, TestAddFieldRealTimeGet,
MinimalSchemaTest, ResponseLogComponentTest, IndexBasedSpellCheckerTest,
ZkCLITest, SimpleCollectionCreateDeleteTest, TestUniqueKeyFieldResource,
HdfsWriteToMultipleCollectionsTest, TestCSVLoader, DOMUtilTest,
CSVRequestHandlerTest, DocValuesMissingTest, MoreLikeThisHandlerTest,
TestLFUCache, TestDynamicFieldResource, TestGroupingSearch,
TestNoOpRegenerator, MultiThreadedOCPTest, BJQParserTest,
UnloadDistributedZkTest, DefaultValueUpdateProcessorTest, NumericFieldsTest,
DistributedMLTComponentTest, PrimitiveFieldTypeTest,
TestRequestStatusCollectionAPI, ChaosMonkeySafeLeaderTest,
BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest,
LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest,
TestReplicationHandler, ClusterStateUpdateTest, ShardRoutingCustomTest,
TestDistributedSearch, AutoCommitTest, TermVectorComponentDistributedTest,
ZkControllerTest, TestRealTimeGet, TestStressReorder, TestReload,
TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest,
TestRangeQuery, TestSolr4Spatial, StatsComponentTest, SolrCmdDistributorTest,
TestSort, TestFiltering, TestFunctionQuery, TestLazyCores,
OverseerCollectionProcessorTest, TestIndexSearcher, HighlighterTest,
ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest,
SimplePostToolTest, TestExtendedDismaxParser, SpellCheckCollatorTest,
SuggesterFSTTest, CoreAdminHandlerTest, DocValuesTest, SuggesterTest, TestTrie,
SpatialFilterTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest,
SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest,
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases,
QueryEqualityTest, DirectUpdateHandlerOptimizeTest, TestRemoteStreaming,
DocValuesMultiTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest,
TestSurroundQueryParser, TestQueryUtils, StandardRequestHandlerTest,
TestWriterPerf, TestOmitPositions, FileBasedSpellCheckerTest,
TermsComponentTest, DocumentBuilderTest, TestValueSourceCache,
PathHierarchyTokenizerFactoryTest, RequiredFieldsTest,
FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir,
FastVectorHighlighterTest, LoggingHandlerTest, SolrPluginUtilsTest,
IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest,
JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest,
PingRequestHandlerTest, TestComponentsName, SearchHandlerTest,
TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test,
TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery,
CopyFieldTest, SolrIndexConfigTest, BadComponentTest, MultiTermTest,
TestDocSet, TestSearchPerf, TestConfig, TestFuzzyAnalyzedSuggestions,
ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, SynonymTokenizerTest,
TestXIncludeConfig, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory,
TestLMJelinekMercerSimilarityFactory, TestFastWriter, TimeZoneUtilsTest,
TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest,
URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest,
ChaosMonkeyNothingIsSafeTest, TestSuggestSpellingConverter, TestSolrJ,
TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest,
SystemInfoHandlerTest, FileUtilsTest, CircularListTest,
DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey,
TestSimpleTrackingShardHandler, TestTolerantSearch, ActionThrottleTest,
AssignTest, CloudExitableDirectoryReaderTest,
CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest,
CustomCollectionTest, DeleteReplicaTest, DeleteShardTest,
DistribCursorPagingTest, DistributedQueueTest, HttpPartitionTest,
LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest,
OverseerRolesTest, RecoveryAfterSoftCommitTest, ReplicationFactorTest]
[junit4] Completed in 57.74s, 1 test, 1 error <<< FAILURES!
[...truncated 273 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958:
There were test failures: 472 suites, 1874 tests, 1 error, 57 ignored (30
assumptions)
Total time: 133 minutes 31 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_31 -client
-XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
