You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/03/05 21:40:13 UTC
[JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_72) - Build # 16
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/16/
Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReqParamsAPI.test
Error Message:
Could not get expected value 'CY val modified' for path 'response/params/y/c' full output: { "responseHeader":{ "status":0, "QTime":0}, "response":{ "znodeVersion":1, "params":{ "x":{ "a":"A val", "b":"B val", "":{"v":0}}, "y":{ "c":"CY val", "b":"BY val", "i":20, "d":[ "val 1", "val 2"], "":{"v":0}}}}}
Stack Trace:
java.lang.AssertionError: Could not get expected value 'CY val modified' for path 'response/params/y/c' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"response":{
"znodeVersion":1,
"params":{
"x":{
"a":"A val",
"b":"B val",
"":{"v":0}},
"y":{
"c":"CY val",
"b":"BY val",
"i":20,
"d":[
"val 1",
"val 2"],
"":{"v":0}}}}}
at __randomizedtesting.SeedInfo.seed([23BFD9387038098D:ABEBE6E2DEC46475]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:458)
at org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:200)
at org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:67)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12058 lines...]
[junit4] Suite: org.apache.solr.handler.TestReqParamsAPI
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\init-core-data-001
[junit4] 2> 3000803 INFO (SUITE-TestReqParamsAPI-seed#[23BFD9387038098D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
[junit4] 2> 3000803 INFO (SUITE-TestReqParamsAPI-seed#[23BFD9387038098D]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 3000805 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3000805 INFO (Thread-7162) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3000805 INFO (Thread-7162) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3000906 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ZkTestServer start zk server on port:63472
[junit4] 2> 3000906 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3000907 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3000909 INFO (zkCallback-2931-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a2f8925 name:ZooKeeperConnection Watcher:127.0.0.1:63472 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3000909 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3000909 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3000909 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 3000912 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3000912 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3000913 INFO (zkCallback-2932-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56ffb97c name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3000914 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3000914 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3000914 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 3000916 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 3000917 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 3000919 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 3000920 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 3000921 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 3000923 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 3000923 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 3000926 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3000926 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3000928 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 3000928 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 3000930 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 3000930 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 3000932 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 3000932 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 3000934 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 3000934 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 3000936 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 3000936 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 3000938 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3000938 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3000940 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 3000940 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 3000942 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 3000942 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 3001330 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1
[junit4] 2> 3001332 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 3001333 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5bd19450{/,null,AVAILABLE}
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7d5c8bc8{HTTP/1.1,[http/1.1]}{127.0.0.1:63479}
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.Server Started @3006084ms
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\tempDir-001/control/data, hostContext=/, hostPort=63479, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores}
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001'
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3001336 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3001337 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3001339 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3001340 INFO (zkCallback-2933-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@45950bf3 name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3001340 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3001340 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3001341 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3001341 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\solr.xml
[junit4] 2> 3001348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores
[junit4] 2> 3001348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CoreContainer New CoreContainer 215851603
[junit4] 2> 3001348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001]
[junit4] 2> 3001349 WARN (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\lib to classpath: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\lib
[junit4] 2> 3001349 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 3001352 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3001353 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3001353 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3001353 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63472/solr
[junit4] 2> 3001353 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3001353 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3001354 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3001355 INFO (zkCallback-2936-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@279845e0 name:ZooKeeperConnection Watcher:127.0.0.1:63472 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3001355 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3001355 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3001357 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3001357 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41018c1d name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3001358 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3001359 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 3001360 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 3001363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 3001365 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 3001366 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 3001368 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 3001369 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 3001371 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 3001372 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 3001373 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3001374 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 3001376 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 3001377 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 3001379 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3001379 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95499714572910596-127.0.0.1:63479_-n_0000000000
[junit4] 2> 3001380 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63479_
[junit4] 2> 3001380 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 3001381 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer Overseer (id=95499714572910596-127.0.0.1:63479_-n_0000000000) starting
[junit4] 2> 3001383 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 3001389 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 3001389 INFO (OverseerCollectionConfigSetProcessor-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 3001389 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 3001389 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63479_
[junit4] 2> 3001390 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:63479_
[junit4] 2> 3001391 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 3001391 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3001392 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 3001403 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3001403 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3001403 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores
[junit4] 2> 3001405 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data\, shard=}
[junit4] 2> 3001405 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1
[junit4] 2> 3001405 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 3001406 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3001406 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63479_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3001407 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3001407 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 3001408 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:63479",
[junit4] 2> "node_name":"127.0.0.1:63479_",
[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> 3001408 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3001408 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:63479",
[junit4] 2> "node_name":"127.0.0.1:63479_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 3001408 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 3001408 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 3001410 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3002408 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 3002408 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 3002408 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3002408 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 3002409 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3002409 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1'
[junit4] 2> 3002409 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3002410 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3002411 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 3002417 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 3002419 WARN (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 3002420 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 3002427 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3002431 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 3002505 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3002511 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 3002512 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3002521 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3002523 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3002525 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3002525 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3002529 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3002529 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 3002529 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3002529 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1], dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\]
[junit4] 2> 3002529 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@608cc609
[junit4] 2> 3002529 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\
[junit4] 2> 3002530 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\index/
[junit4] 2> 3002530 WARN (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 3002530 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\index
[junit4] 2> 3002530 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=45.74609375, floorSegmentMB=0.5205078125, forceMergeDeletesPctAllowed=6.472604529193584, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1386524974315096
[junit4] 2> 3002530 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5efdf844 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b617354),segFN=segments_1,generation=1}
[junit4] 2> 3002530 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3002531 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\
[junit4] 2> 3002531 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\control-001\cores\collection1\data\ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 3002532 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3002533 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3002533 WARN (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 3002536 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3002537 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3002538 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3002538 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3002541 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 3002541 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3002542 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3002542 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3002543 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3002543 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3002543 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1932770664, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 3002543 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5efdf844 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b617354),segFN=segments_1,generation=1}
[junit4] 2> 3002544 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3002544 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@720aba7a[collection1] main]
[junit4] 2> 3002544 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 3002545 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3002545 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 3002545 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3002545 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 3002545 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3002546 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3002546 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3002546 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 3002546 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 3002546 INFO (searcherExecutor-8624-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@720aba7a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3002547 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 3002547 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 3002547 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 3002547 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1527995434988994560
[junit4] 2> 3002548 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1527995434988994560
[junit4] 2> 3002548 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 3002549 INFO (coreLoadExecutor-8623-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 3002549 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:63479 collection:control_collection shard:shard1
[junit4] 2> 3002550 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 3002553 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/95499714572910596-core_node1-n_0000000000
[junit4] 2> 3002554 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 3002555 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3002556 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3002556 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63479/collection1/
[junit4] 2> 3002556 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3002556 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:63479/collection1/ has no replicas
[junit4] 2> 3002556 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 3002556 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 3002559 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95499714572910596-core_node1-n_0000000000
[junit4] 2> 3002560 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63479/collection1/ shard1
[junit4] 2> 3002561 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer 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:63479",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 3002663 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3002713 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:63479/collection1/ and leader is http://127.0.0.1:63479/collection1/
[junit4] 2> 3002713 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:63479
[junit4] 2> 3002713 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3002713 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 3002713 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 3002715 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer 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:63479",
[junit4] 2> "node_name":"127.0.0.1:63479_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 3002715 INFO (coreZkRegister-8617-thread-1-processing-n:127.0.0.1:63479_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:63479_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 3002715 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ReplicaMutator Update state numShards=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:63479",
[junit4] 2> "node_name":"127.0.0.1:63479_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 3002818 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3002908 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3002908 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3002909 INFO (zkCallback-2939-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2830d4e0 name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3002909 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3002909 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3002909 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3002910 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3002911 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 3002911 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 3002911 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3002912 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3002913 INFO (zkCallback-2940-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39089e1c name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3002913 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3002913 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3002915 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 3002915 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 3003019 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
[junit4] 2> 3003340 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores\collection1
[junit4] 2> 3003342 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001
[junit4] 2> 3003343 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 3003346 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@129adb5c{/,null,AVAILABLE}
[junit4] 2> 3003347 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@810b949{HTTP/1.1,[http/1.1]}{127.0.0.1:63502}
[junit4] 2> 3003348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.e.j.s.Server Started @3008096ms
[junit4] 2> 3003348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=63502, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores}
[junit4] 2> 3003348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 3003348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001'
[junit4] 2> 3003348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3003348 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3003349 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3003350 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3003351 INFO (zkCallback-2941-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c8ed416 name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3003351 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3003351 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3003352 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3003352 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\solr.xml
[junit4] 2> 3003359 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores
[junit4] 2> 3003359 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CoreContainer New CoreContainer 460089550
[junit4] 2> 3003359 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001]
[junit4] 2> 3003359 WARN (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\lib to classpath: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\lib
[junit4] 2> 3003360 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 3003363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3003363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3003363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3003363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63472/solr
[junit4] 2> 3003363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3003363 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3003364 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3003365 INFO (zkCallback-2944-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a6aa4a3 name:ZooKeeperConnection Watcher:127.0.0.1:63472 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3003365 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3003365 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3003366 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3003367 INFO (zkCallback-2945-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46fc30a4 name:ZooKeeperConnection Watcher:127.0.0.1:63472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3003367 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3003373 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3003375 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3003377 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3003377 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95499714572910601-127.0.0.1:63502_-n_0000000001
[junit4] 2> 3003378 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95499714572910596-127.0.0.1:63479_-n_0000000000 to know if I could be the leader
[junit4] 2> 3003378 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.ZkController Publish node=127.0.0.1:63502_ as DOWN
[junit4] 2> 3003380 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:63502_"} current state version: 3
[junit4] 2> 3003380 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:63502_
[junit4] 2> 3003381 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 3003485 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3003485 INFO (zkCallback-2945-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3003485 INFO (zkCallback-2939-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3004380 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63502_
[junit4] 2> 3004380 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:63502_
[junit4] 2> 3004381 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3004382 INFO (zkCallback-2939-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3004381 INFO (zkCallback-2945-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3004383 INFO (zkCallback-2937-thread-1-processing-n:127.0.0.1:63479_) [n:127.0.0.1:63479_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3004383 INFO (zkCallback-2939-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3004383 INFO (zkCallback-2945-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3004390 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3004390 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3004391 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores
[junit4] 2> 3004394 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data\, shard=}
[junit4] 2> 3004394 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores\collection1
[junit4] 2> 3004394 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 3004395 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3004395 INFO (TEST-TestReqParamsAPI.test-seed#[23BFD9387038098D]) [n:127.0.0.1:63502_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3004396 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3004396 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 3004397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1
[junit4] 2> 3004397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1]
[junit4] 2> 3004397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [1]
[junit4] 2> 3004397 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:63502",
[junit4] 2> "node_name":"127.0.0.1:63502_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 3004397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3004398 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:63502",
[junit4] 2> "node_name":"127.0.0.1:63502_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 3004398 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 3004398 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 3004501 INFO (OverseerStateUpdate-95499714572910596-127.0.0.1:63479_-n_0000000000) [n:127.0.0.1:63479_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 3004502 INFO (zkCallback-2945-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3004503 INFO (zkCallback-2945-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [1] to [2]
[junit4] 2> 3005397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 3005397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
[junit4] 2> 3005397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3005397 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1]
[junit4] 2> 3005398 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3005398 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores\collection1'
[junit4] 2> 3005398 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3005398 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3005400 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 3005407 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 3005408 WARN (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 3005409 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 3005415 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3005421 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 3005503 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3005510 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 3005511 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3005519 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3005521 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3005523 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3005524 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3005528 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3005528 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 3005528 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3005528 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:63502_) [n:127.0.0.1:63502_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001\shard-1-001\cores\collection1], dataDir=[C:\Users\JenkinsSlav
[...truncated too long message...]
t4] 2> 6 /solr/aliases.json
[junit4] 2> 5 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReqParamsAPI -Dtests.method=test -Dtests.seed=23BFD9387038098D -Dtests.slow=true -Dtests.locale=el-GR -Dtests.timezone=Africa/Johannesburg -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 34.7s J0 | TestReqParamsAPI.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected value 'CY val modified' for path 'response/params/y/c' full output: {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "response":{
[junit4] > "znodeVersion":1,
[junit4] > "params":{
[junit4] > "x":{
[junit4] > "a":"A val",
[junit4] > "b":"B val",
[junit4] > "":{"v":0}},
[junit4] > "y":{
[junit4] > "c":"CY val",
[junit4] > "b":"BY val",
[junit4] > "i":20,
[junit4] > "d":[
[junit4] > "val 1",
[junit4] > "val 2"],
[junit4] > "":{"v":0}}}}}
[junit4] > at __randomizedtesting.SeedInfo.seed([23BFD9387038098D:ABEBE6E2DEC46475]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:458)
[junit4] > at org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:200)
[junit4] > at org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:67)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3035500 INFO (SUITE-TestReqParamsAPI-seed#[23BFD9387038098D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReqParamsAPI_23BFD9387038098D-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=el-GR, timezone=Africa/Johannesburg
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=1,free=274512496,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestUseDocValuesAsStored, CloudExitableDirectoryReaderTest, DistribDocExpirationUpdateProcessorTest, ScriptEngineTest, TestStressReorder, SimpleMLTQParserTest, SolrRequestParserTest, TestCoreContainer, ClusterStateUpdateTest, AddBlockUpdateTest, OverseerTaskQueueTest, RestartWhileUpdatingTest, HardAutoCommitTest, BlockJoinFacetDistribTest, TestSchemaResource, RegexBoostProcessorTest, TestSolrXml, QueryEqualityTest, SuggesterFSTTest, TestDocumentBuilder, CdcrRequestHandlerTest, TestPostingsSolrHighlighter, VMParamsZkACLAndCredentialsProvidersTest, TestDownShardTolerantSearch, TestDistribIDF, TestSolrCLIRunExample, CollectionStateFormat2Test, HdfsDirectoryTest, BlockDirectoryTest, XmlUpdateRequestHandlerTest, TestAnalyzeInfixSuggestions, DeleteStatusTest, CircularListTest, DistributedSpellCheckComponentTest, ClusterStateTest, AsyncMigrateRouteKeyTest, TestPseudoReturnFields, TestLuceneMatchVersion, TestCodecSupport, TestExtendedDismaxParser, FullHLLTest, TestBlobHandler, TestDefaultSearchFieldResource, HLLUtilTest, TestDistributedStatsComponentCardinality, TestRestManager, RecoveryZkTest, FastVectorHighlighterTest, TestHighlightDedupGrouping, JavabinLoaderTest, AnalysisAfterCoreReloadTest, TestSort, TestIntervalFaceting, TestRemoveLastDynamicCopyField, DocumentBuilderTest, SOLR749Test, PrimUtilsTest, TestRequestStatusCollectionAPI, SimplePostToolTest, ReturnFieldsTest, TestImplicitCoreProperties, TestQuerySenderNoQuery, DistributedFacetPivotLargeTest, TestExactStatsCache, TimeZoneUtilsTest, TestManagedSchemaFieldResource, TestLFUCache, CoreAdminCreateDiscoverTest, HdfsBasicDistributedZkTest, MBeansHandlerTest, TlogReplayBufferedWhileIndexingTest, CachingDirectoryFactoryTest, TermsComponentTest, CurrencyFieldXmlFileTest, SecurityConfHandlerTest, OverseerTest, TestStressLucene, TestSchemaVersionResource, BitVectorTest, OverseerRolesTest, TestConfigSetImmutable, TestPivotHelperCode, TestLeaderElectionZkExpiry, TestComponentsName, SoftAutoCommitTest, TestManagedResource, TestCloudManagedSchema, TestCloudInspectUtil, TestSurroundQueryParser, DistributedQueryComponentOptimizationTest, CollectionsAPIAsyncDistributedZkTest, TestClassNameShortening, SchemaVersionSpecificBehaviorTest, SSLMigrationTest, TestHighFrequencyDictionaryFactory, TestElisionMultitermQuery, TestScoreJoinQPNoScore, RuleEngineTest, TestLRUCache, ChangedSchemaMergeTest, CdcrUpdateLogTest, TestObjectReleaseTracker, DirectUpdateHandlerTest, TestCryptoKeys, TestAuthenticationFramework, TestJmxMonitoredMap, TestRandomRequestDistribution, UpdateParamsTest, BasicAuthIntegrationTest, TestCursorMarkWithoutUniqueKey, TestXIncludeConfig, TestConfigSetsAPIExclusivity, DOMUtilTest, TestIBSimilarityFactory, TestInitQParser, RankQueryTest, TestRemoteStreaming, TestLMDirichletSimilarityFactory, TestSizeLimitedDistributedMap, TestFaceting, ConnectionManagerTest, PreAnalyzedUpdateProcessorTest, BasicDistributedZkTest, ConcurrentDeleteAndCreateCollectionTest, RemoteQueryErrorTest, TestReplicaProperties, PeerSyncTest, TestJsonRequest, SolrCloudExampleTest, LeaderElectionTest, ZkStateWriterTest, DistributedQueueTest, SharedFSAutoReplicaFailoverUtilsTest, TestInitParams, TestReRankQParserPlugin, TestPhraseSuggestions, LeaderFailoverAfterPartitionTest, TestWordDelimiterFilterFactory, TestMiniSolrCloudCluster, SliceStateTest, ResponseHeaderTest, TestReplicationHandler, ShardRoutingTest, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, SolrIndexSplitterTest, TestCoreDiscovery, DocValuesTest, SuggesterTest, SpatialFilterTest, TestCSVLoader, PolyFieldTest, TestUpdate, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest, DistanceFunctionTest, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TestValueSourceCache, TestIndexingPerformance, RequiredFieldsTest, TestArbitraryIndexDir, IndexSchemaTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestAnalyzedSuggestions, TestBinaryResponseWriter, HighlighterConfigTest, TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, SampleTest, TestDocSet, TestSearchPerf, TestConfig, OutputWriterTest, ExternalFileFieldSortTest, TestSolrCoreProperties, TestCharFilters, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestFastWriter, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PreAnalyzedFieldTest, DateFieldTest, SpellingQueryConverterTest, TestSolrJ, SystemInfoHandlerTest, UUIDFieldTest, DistributedMLTComponentTest, TestRTGBase, CursorPagingTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestSimpleTrackingShardHandler, TestTolerantSearch, TestEmbeddedSolrServerConstructors, ActionThrottleTest, AssignTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CollectionReloadTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, DistribJoinFromCollectionTest, LeaderInitiatedRecoveryOnCommitTest, LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, MultiThreadedOCPTest, ShardSplitTest, TestLeaderInitiatedRecoveryThread, TestShortCircuitedRequests, ExitableDirectoryReaderTest, QueryResultKeyTest, TestNRTOpen, TestShardHandlerFactory, TestSolrConfigHandler, TestReqParamsAPI]
[junit4] Completed [481/583 (1!)] on J0 in 34.72s, 1 test, 1 failure <<< FAILURES!
[...truncated 334 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\common-build.xml:524: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1457: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1014: There were test failures: 583 suites (8 ignored), 2352 tests, 1 failure, 127 ignored (81 assumptions) [seed: 23BFD9387038098D]
Total time: 75 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_72) - Build # 17
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/17/
Java: 32bit/jdk1.8.0_72 -client -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
Could not get expected value 'X val' for path 'x' full output: { "responseHeader":{ "status":0, "QTime":0}, "params":{"wt":"json"}, "context":{ "webapp":"", "path":"/test1", "httpMethod":"GET"}, "class":"org.apache.solr.core.BlobStoreTestRequestHandler", "x":null}
Stack Trace:
java.lang.AssertionError: Could not get expected value 'X val' for path 'x' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"params":{"wt":"json"},
"context":{
"webapp":"",
"path":"/test1",
"httpMethod":"GET"},
"class":"org.apache.solr.core.BlobStoreTestRequestHandler",
"x":null}
at __randomizedtesting.SeedInfo.seed([81D32C055F7F2109:599E0152A8A284A9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:458)
at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:238)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12056 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\init-core-data-001
[junit4] 2> 3111782 INFO (SUITE-TestDynamicLoading-seed#[81D32C055F7F2109]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 3111782 INFO (SUITE-TestDynamicLoading-seed#[81D32C055F7F2109]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 3111786 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3111786 INFO (Thread-7119) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3111786 INFO (Thread-7119) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3111886 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ZkTestServer start zk server on port:51194
[junit4] 2> 3111886 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3111888 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3111891 INFO (zkCallback-2861-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c7f60 name:ZooKeeperConnection Watcher:127.0.0.1:51194 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3111891 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3111891 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3111891 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 3111894 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3111895 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3111897 INFO (zkCallback-2862-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13e3a4b name:ZooKeeperConnection Watcher:127.0.0.1:51194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3111897 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3111897 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3111898 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 3111901 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 3111904 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 3111906 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 3111909 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 3111909 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 3111913 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 3111913 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 3111915 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3111915 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3111918 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 3111918 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 3111920 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 3111920 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 3111923 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 3111924 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 3111926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 3111926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 3111928 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 3111929 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 3111931 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3111931 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3111934 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 3111934 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 3111936 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 3111937 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 3112688 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1
[junit4] 2> 3112692 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 3112695 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@964cb6{/,null,AVAILABLE}
[junit4] 2> 3112700 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1874ee4{HTTP/1.1,[http/1.1]}{127.0.0.1:51201}
[junit4] 2> 3112700 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.Server Started @3109328ms
[junit4] 2> 3112700 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\tempDir-001/control/data, hostContext=/, hostPort=51201, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores}
[junit4] 2> 3112700 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 3112700 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001'
[junit4] 2> 3112701 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3112701 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3112701 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3112703 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3112704 INFO (zkCallback-2863-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f10f54 name:ZooKeeperConnection Watcher:127.0.0.1:51194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3112704 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3112705 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3112707 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3112707 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\solr.xml
[junit4] 2> 3112727 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores
[junit4] 2> 3112727 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CoreContainer New CoreContainer 6417546
[junit4] 2> 3112727 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001]
[junit4] 2> 3112727 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\lib to classpath: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\lib
[junit4] 2> 3112727 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 3112731 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3112732 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3112732 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3112732 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51194/solr
[junit4] 2> 3112732 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3112732 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3112733 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3112735 INFO (zkCallback-2866-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1899983 name:ZooKeeperConnection Watcher:127.0.0.1:51194 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3112735 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3112736 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3112738 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3112740 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ad71bb name:ZooKeeperConnection Watcher:127.0.0.1:51194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3112740 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3112742 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 3112745 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 3112748 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 3112751 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 3112754 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 3112757 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 3112759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 3112760 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 3112762 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 3112763 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3112766 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 3112769 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 3112771 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 3112773 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3112774 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95500534239068164-127.0.0.1:51201_-n_0000000000
[junit4] 2> 3112774 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51201_
[junit4] 2> 3112774 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 3112776 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer Overseer (id=95500534239068164-127.0.0.1:51201_-n_0000000000) starting
[junit4] 2> 3112778 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 3112786 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 3112787 INFO (OverseerCollectionConfigSetProcessor-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 3112787 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51201_
[junit4] 2> 3112787 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 3112788 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51201_
[junit4] 2> 3112789 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 3112789 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3112790 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 3112825 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3112825 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3112827 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores
[junit4] 2> 3112828 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data\, shard=}
[junit4] 2> 3112828 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1
[junit4] 2> 3112828 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 3112830 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1
[junit4] 2> 3112830 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51201_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3112830 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3112830 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 3112832 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51201",
[junit4] 2> "node_name":"127.0.0.1:51201_",
[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> 3112832 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3112833 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51201",
[junit4] 2> "node_name":"127.0.0.1:51201_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 3112833 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 3112834 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 3112836 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3113833 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 3113833 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 3113833 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3113834 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 3113834 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3113834 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1'
[junit4] 2> 3113834 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3113834 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3113836 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 3113860 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 3113868 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 3113883 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3113930 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 3114056 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3114068 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 3114069 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3114083 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3114088 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3114092 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3114094 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3114098 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1
[junit4] 2> 3114098 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 3114098 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3114098 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1], dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\]
[junit4] 2> 3114099 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@11029a8
[junit4] 2> 3114100 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\
[junit4] 2> 3114100 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\index/
[junit4] 2> 3114100 WARN (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 3114100 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\index
[junit4] 2> 3114101 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@10c3479
[junit4] 2> 3114101 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@69ae84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1499556),segFN=segments_1,generation=1}
[junit4] 2> 3114101 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3114102 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\
[junit4] 2> 3114102 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\control-001\cores\collection1\data\ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 3114106 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3114107 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3114107 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3114107 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3114107 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3114107 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3114107 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3114108 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3114108 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3114108 WARN (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 3114120 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3114121 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3114122 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3114124 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 3114133 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 3114134 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3114136 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3114136 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3114138 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3114138 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3114138 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 3114139 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@69ae84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1499556),segFN=segments_1,generation=1}
[junit4] 2> 3114139 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 3114139 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c698d0[collection1] main]
[junit4] 2> 3114139 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 3114140 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3114140 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 3114141 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3114141 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 3114141 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3114142 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3114142 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3114142 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 3114142 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 3114144 INFO (searcherExecutor-8999-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c698d0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3114144 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 3114144 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 3114144 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 3114144 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1528008550294487040
[junit4] 2> 3114146 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1528008550294487040
[junit4] 2> 3114146 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 3114146 INFO (coreLoadExecutor-8998-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 3114147 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:51201 collection:control_collection shard:shard1
[junit4] 2> 3114148 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 3114152 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/95500534239068164-core_node1-n_0000000000
[junit4] 2> 3114152 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 3114155 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3114155 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3114155 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51201/collection1/
[junit4] 2> 3114155 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3114155 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51201/collection1/ has no replicas
[junit4] 2> 3114156 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 3114156 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 3114160 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95500534239068164-core_node1-n_0000000000
[junit4] 2> 3114162 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51201/collection1/ shard1
[junit4] 2> 3114163 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer 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:51201",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 3114266 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3114315 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:51201/collection1/ and leader is http://127.0.0.1:51201/collection1/
[junit4] 2> 3114315 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51201
[junit4] 2> 3114315 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3114315 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 3114315 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 3114319 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer 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:51201",
[junit4] 2> "node_name":"127.0.0.1:51201_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 3114320 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ReplicaMutator Update state numShards=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:51201",
[junit4] 2> "node_name":"127.0.0.1:51201_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 3114320 INFO (coreZkRegister-8992-thread-1-processing-n:127.0.0.1:51201_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51201_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 3114332 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3114333 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3114334 INFO (zkCallback-2869-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@125b93f name:ZooKeeperConnection Watcher:127.0.0.1:51194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3114335 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3114335 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3114335 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3114337 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3114337 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 3114425 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3114425 INFO (zkCallback-2869-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3114862 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores\collection1
[junit4] 2> 3114864 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001
[junit4] 2> 3114865 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 3114868 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13c571e{/,null,AVAILABLE}
[junit4] 2> 3114870 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.ServerConnector Started ServerConnector@70e98d{HTTP/1.1,[http/1.1]}{127.0.0.1:51225}
[junit4] 2> 3114870 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.e.j.s.Server Started @3111499ms
[junit4] 2> 3114870 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=51225, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores}
[junit4] 2> 3114870 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 3114871 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001'
[junit4] 2> 3114871 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3114871 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3114872 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3114872 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3114873 INFO (zkCallback-2870-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f6badf name:ZooKeeperConnection Watcher:127.0.0.1:51194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3114874 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3114874 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3114877 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3114877 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\solr.xml
[junit4] 2> 3114893 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores
[junit4] 2> 3114893 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CoreContainer New CoreContainer 14215642
[junit4] 2> 3114893 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001]
[junit4] 2> 3114893 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\lib to classpath: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\lib
[junit4] 2> 3114895 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 3114900 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3114901 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3114901 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3114901 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51194/solr
[junit4] 2> 3114901 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 3114901 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3114903 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3114904 INFO (zkCallback-2873-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1eec80c name:ZooKeeperConnection Watcher:127.0.0.1:51194 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3114905 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3114905 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3114908 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3114910 INFO (zkCallback-2874-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56d140 name:ZooKeeperConnection Watcher:127.0.0.1:51194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3114910 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3114918 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3114920 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3114924 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3114924 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95500534239068168-127.0.0.1:51225_-n_0000000001
[junit4] 2> 3114925 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95500534239068164-127.0.0.1:51201_-n_0000000000 to know if I could be the leader
[junit4] 2> 3114926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.ZkController Publish node=127.0.0.1:51225_ as DOWN
[junit4] 2> 3114929 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:51225_"} current state version: 3
[junit4] 2> 3114929 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:51225_
[junit4] 2> 3115033 INFO (zkCallback-2874-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3115034 INFO (zkCallback-2869-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3115033 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3115929 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51225_
[junit4] 2> 3115929 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51225_
[junit4] 2> 3115931 INFO (zkCallback-2874-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3115931 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3115931 INFO (zkCallback-2869-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 3115932 INFO (zkCallback-2874-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3115932 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3115932 INFO (zkCallback-2869-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3115953 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 3115953 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 3115954 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores
[junit4] 2> 3115956 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data\, shard=}
[junit4] 2> 3115956 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores\collection1
[junit4] 2> 3115956 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 3115957 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1
[junit4] 2> 3115957 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3115957 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 3115957 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[81D32C055F7F2109]) [n:127.0.0.1:51225_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 3115960 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3115960 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51225",
[junit4] 2> "node_name":"127.0.0.1:51225_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 3115960 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51225",
[junit4] 2> "node_name":"127.0.0.1:51225_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 3115960 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 3115961 INFO (OverseerStateUpdate-95500534239068164-127.0.0.1:51201_-n_0000000000) [n:127.0.0.1:51201_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 3116064 INFO (zkCallback-2867-thread-1-processing-n:127.0.0.1:51201_) [n:127.0.0.1:51201_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
[junit4] 2> 3116064 INFO (zkCallback-2869-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
[junit4] 2> 3116064 INFO (zkCallback-2874-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
[junit4] 2> 3116960 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 3116960 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
[junit4] 2> 3116961 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 3116961 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1]
[junit4] 2> 3116961 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 3116961 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores\collection1'
[junit4] 2> 3116961 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3116961 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3116964 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 3116975 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 3116980 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 3116990 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3117002 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 3117108 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3117118 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 3117119 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 3117138 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3117143 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 3117147 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3117149 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3117154 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1
[junit4] 2> 3117154 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 3117154 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3117155 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores\collection1], dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001\shard-1-001\cores\collection1\data\]
[junit4] 2> 3117155 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@11029a8
[junit4] 2> 3117156 INFO (coreLoadExecutor-9009-thread-1-processing-n:127.0.0.1:51225_) [n:127.0.0.1:51225_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\t
[...truncated too long message...]
r/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/configs/.system/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading -Dtests.method=testDynamicLoading -Dtests.seed=81D32C055F7F2109 -Dtests.slow=true -Dtests.locale=de-AT -Dtests.timezone=Europe/Bratislava -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 59.8s J1 | TestDynamicLoading.testDynamicLoading <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected value 'X val' for path 'x' full output: {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "params":{"wt":"json"},
[junit4] > "context":{
[junit4] > "webapp":"",
[junit4] > "path":"/test1",
[junit4] > "httpMethod":"GET"},
[junit4] > "class":"org.apache.solr.core.BlobStoreTestRequestHandler",
[junit4] > "x":null}
[junit4] > at __randomizedtesting.SeedInfo.seed([81D32C055F7F2109:599E0152A8A284A9]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:458)
[junit4] > at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:238)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3171589 INFO (SUITE-TestDynamicLoading-seed#[81D32C055F7F2109]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestDynamicLoading_81D32C055F7F2109-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {blobName=PostingsFormat(name=Direct), size=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=Lucene50(blocksize=128), id=PostingsFormat(name=Memory doPackFST= false), version=Lucene50(blocksize=128), md5=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Direct)}, docValues:{}, maxPointsInLeafNode=1151, maxMBSortInHeap=4.819908778399804, sim=ClassicSimilarity, locale=de-AT, timezone=Europe/Bratislava
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_72 (32-bit)/cpus=3,threads=1,free=268590456,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [DistributedSuggestComponentTest, CopyFieldTest, TestDistributedGrouping, TestCopyFieldCollectionResource, LoggingHandlerTest, RulesTest, DateRangeFieldTest, TestIndexingPerformance, NumericFieldsTest, MultiTermTest, TestFastWriter, ConnectionReuseTest, DateMathParserTest, TestRecoveryHdfs, TestBlendedInfixSuggestions, DateFieldTest, RollingRestartTest, BlockJoinFacetRandomTest, TestExclusionRuleCollectionAccess, TermVectorComponentTest, OverseerStatusTest, TestBinaryResponseWriter, TestSchemaManager, TestPerFieldSimilarity, CloudMLTQParserTest, DistributedExpandComponentTest, DefaultValueUpdateProcessorTest, SolrXmlInZkTest, WordBreakSolrSpellCheckerTest, TestCrossCoreJoin, TestMaxScoreQueryParser, TestWriterPerf, StatsComponentTest, TestZkChroot, CleanupOldIndexTest, TestRangeQuery, CheckHdfsIndexTest, SolrInfoMBeanTest, ParsingFieldUpdateProcessorsTest, CollectionTooManyReplicasTest, PluginInfoTest, CacheHeaderTest, TestPerFieldSimilarityWithDefaultOverride, DistributedTermsComponentTest, FieldMutatingUpdateProcessorTest, UpdateRequestProcessorFactoryTest, HighlighterMaxOffsetTest, TermVectorComponentDistributedTest, TestSchemaSimilarityResource, ZkStateReaderTest, SaslZkACLProviderTest, TestJoin, TestCoreDiscovery, TestStressRecovery, EchoParamsTest, TestReload, TestTrie, TestConfigSetsAPIZkFailure, CustomCollectionTest, SolrPluginUtilsTest, QueryParsingTest, DebugComponentTest, TestTestInjection, TestJettySolrRunner, LeaderElectionIntegrationTest, TestQuerySenderListener, TestFreeTextSuggestions, TestRTimerTree, OverseerCollectionConfigSetProcessorTest, TestSuggestSpellingConverter, HdfsThreadLeakTest, FieldAnalysisRequestHandlerTest, SynonymTokenizerTest, TestConfigOverlay, FileUtilsTest, CdcrVersionReplicationTest, TestSolrQueryParserResource, TestRawTransformer, CurrencyFieldOpenExchangeTest, TestFuzzyAnalyzedSuggestions, TestInfoStreamLogging, DistribCursorPagingTest, HttpPartitionTest, EnumFieldTest, TestSolrDynamicMBean, TestOrdValues, OpenCloseCoreStressTest, SortSpecParsingTest, TestUtils, TestTrackingShardHandlerFactory, RequestHandlersTest, DistributedIntervalFacetingTest, TestQueryWrapperFilter, SpellPossibilityIteratorTest, TestRecovery, SpellCheckCollatorTest, NotRequiredUniqueKeyTest, HdfsDirectoryFactoryTest, HdfsRestartWhileUpdatingTest, TestManagedStopFilterFactory, BigEndianAscendingWordSerializerTest, TestPHPSerializedResponseWriter, TestSolrCoreProperties, TestConfigSetProperties, TestSearcherReuse, DirectoryFactoryTest, BJQParserTest, TestHashQParserPlugin, TestDistributedMissingSort, RAMDirectoryFactoryTest, DistributedQueryComponentCustomSortTest, TestReversedWildcardFilterFactory, TestFoldingMultitermQuery, ReplicationFactorTest, MigrateRouteKeyTest, BasicFunctionalityTest, CoreAdminRequestStatusTest, TestCloudPivotFacet, TestDynamicFieldCollectionResource, TestLMJelinekMercerSimilarityFactory, TestRequestForwarding, HdfsBasicDistributedZk2Test, CollectionsAPIDistributedZkTest, ConvertedLegacyTest, FullSolrCloudDistribCmdsTest, TestSolrJ, TestSortingResponseWriter, IndexSchemaRuntimeFieldTest, BasicZkTest, TestNRTOpen, TestFieldTypeCollectionResource, TestPKIAuthenticationPlugin, PKIAuthenticationIntegrationTest, BufferStoreTest, TestRandomFaceting, MergeStrategyTest, DistributedVersionInfoTest, SpellCheckComponentTest, TestCSVResponseWriter, TestBinaryField, AnalyticsQueryTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestReqParamsAPI, TestLRUStatsCache, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OverseerTest, TestReplicationHandler, AutoCommitTest, TestRealTimeGet, SimpleFacetsTest, TestSolr4Spatial, TestGroupingSearch, SolrCmdDistributorTest, TestFunctionQuery, TestLazyCores, SoftAutoCommitTest, SolrIndexSplitterTest, TestExtendedDismaxParser, SolrRequestParserTest, DocValuesTest, SuggesterTSTTest, SuggesterWFSTTest, NoCacheHeaderTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestWordDelimiterFilterFactory, StatelessScriptUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, TermsComponentTest, DocumentBuilderTest, TestValueSourceCache, MoreLikeThisHandlerTest, FastVectorHighlighterTest, TestCollationField, TestAnalyzedSuggestions, CSVRequestHandlerTest, SearchHandlerTest, TestFastLRUCache, TestSystemIdResolver, SpellingQueryConverterTest, TestLRUCache, SliceStateTest, TestRTGBase, CursorPagingTest, ActionThrottleTest, AliasIntegrationTest, CollectionsAPIAsyncDistributedZkTest, DeleteShardTest, DistribJoinFromCollectionTest, LeaderInitiatedRecoveryOnCommitTest, LeaderInitiatedRecoveryOnShardRestartTest, OverseerTaskQueueTest, RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, RestartWhileUpdatingTest, SSLMigrationTest, SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, TestCloudDeleteByQuery, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestCryptoKeys, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterBase, TestRequestStatusCollectionAPI, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, VMParamsZkACLAndCredentialsProvidersTest, CachingDirectoryFactoryTest, TestDynamicLoading]
[junit4] Completed [480/583 (1!)] on J1 in 59.87s, 1 test, 1 failure <<< FAILURES!
[...truncated 341 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\solr\common-build.xml:524: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1457: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1014: There were test failures: 583 suites (8 ignored), 2352 tests, 1 failure, 127 ignored (81 assumptions) [seed: 81D32C055F7F2109]
Total time: 79 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any