lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Jan Høydahl <jan...@apache.org>
Subject Re: [JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_121) - Build # 6375 - Still Unstable!
Date Mon, 30 Jan 2017 00:56:34 GMT
Ah, of course, the tests on Windows fail since "/foo/bar” from the test is not an absolute path on Windows.
I’ll commit a fix tomorrow.

--
Jan Høydahl
Search Solution architect
Cominvent AS
www.cominvent.com
+47 90125809

> 30. jan. 2017 kl. 00.49 skrev Policeman Jenkins Server <jenkins@thetaphi.de>:
> 
> Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6375/
> Java: 32bit/jdk1.8.0_121 -server -XX:+UseParallelGC
> 
> 2 tests failed.
> FAILED:  org.apache.solr.handler.TestReplicationHandler.doTestIllegalFilePaths
> 
> Error Message:
> Should have thrown exception on illegal path for param file and file name /foo/bar
> 
> Stack Trace:
> java.lang.AssertionError: Should have thrown exception on illegal path for param file and file name /foo/bar
> 	at __randomizedtesting.SeedInfo.seed([C908CBE9EBEACE10:3D17E19AFA28A889]:0)
> 	at org.junit.Assert.fail(Assert.java:93)
> 	at org.apache.solr.handler.TestReplicationHandler.doTestIllegalFilePaths(TestReplicationHandler.java:1436)
> 	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:1713)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
> 	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:368)
> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
> 	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:368)
> 	at java.lang.Thread.run(Thread.java:745)
> 
> 
> FAILED:  org.apache.solr.handler.TestSQLHandler.doTest
> 
> Error Message:
> 
> 
> Stack Trace:
> java.lang.AssertionError
> 	at __randomizedtesting.SeedInfo.seed([C908CBE9EBEACE10:6E4C734D8651DDA9]:0)
> 	at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:395)
> 	at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:84)
> 	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:1713)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
> 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
> 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
> 	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:368)
> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
> 	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:368)
> 	at java.lang.Thread.run(Thread.java:745)
> 
> 
> 
> 
> Build Log:
> [...truncated 11342 lines...]
>   [junit4] Suite: org.apache.solr.handler.TestSQLHandler
>   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\init-core-data-001
>   [junit4]   2> 605841 INFO  (SUITE-TestSQLHandler-seed#[C908CBE9EBEACE10]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields
>   [junit4]   2> 605847 INFO  (SUITE-TestSQLHandler-seed#[C908CBE9EBEACE10]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
>   [junit4]   2> 605848 INFO  (SUITE-TestSQLHandler-seed#[C908CBE9EBEACE10]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /mdh/a
>   [junit4]   2> 605850 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
>   [junit4]   2> 605851 INFO  (Thread-969) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
>   [junit4]   2> 605851 INFO  (Thread-969) [    ] o.a.s.c.ZkTestServer Starting server
>   [junit4]   2> 605951 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkTestServer start zk server on port:51076
>   [junit4]   2> 605970 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-sql.xml to /configs/conf1/solrconfig.xml
>   [junit4]   2> 605974 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\schema-sql.xml to /configs/conf1/schema.xml
>   [junit4]   2> 605977 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
>   [junit4]   2> 605979 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
>   [junit4]   2> 605982 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
>   [junit4]   2> 605985 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
>   [junit4]   2> 605987 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
>   [junit4]   2> 605990 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
>   [junit4]   2> 605994 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
>   [junit4]   2> 605996 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
>   [junit4]   2> 605999 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
>   [junit4]   2> 606001 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec6826580001, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 606650 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\control-001\cores\collection1
>   [junit4]   2> 606654 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
>   [junit4]   2> 606655 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cc4a3c{/mdh/a,null,AVAILABLE}
>   [junit4]   2> 606660 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5d91f1{HTTP/1.1,[http/1.1]}{127.0.0.1:51083}
>   [junit4]   2> 606660 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server Started @612276ms
>   [junit4]   2> 606660 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\tempDir-001/control/data, hostContext=/mdh/a, hostPort=51083, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\control-001\cores}
>   [junit4]   2> 606660 ERROR (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
>   [junit4]   2> 606660 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
>   [junit4]   2> 606660 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
>   [junit4]   2> 606660 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
>   [junit4]   2> 606661 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-29T22:46:06.462Z
>   [junit4]   2> 606666 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec6826580002, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 606666 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
>   [junit4]   2> 606666 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\control-001\solr.xml
>   [junit4]   2> 606683 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51076/solr
>   [junit4]   2> 606717 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51083_mdh%2Fa
>   [junit4]   2> 606719 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.Overseer Overseer (id=97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) starting
>   [junit4]   2> 606730 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51083_mdh%2Fa
>   [junit4]   2> 606732 INFO  (OverseerStateUpdate-97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
>   [junit4]   2> 606857 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\control-001\cores
>   [junit4]   2> 606858 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
>   [junit4]   2> 606863 INFO  (OverseerStateUpdate-97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
>   [junit4]   2> 607879 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
>   [junit4]   2> 607887 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
>   [junit4]   2> 607934 WARN  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
>   [junit4]   2> 607935 WARN  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
>   [junit4]   2> 607935 WARN  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
>   [junit4]   2> 607935 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
>   [junit4]   2> 607940 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
>   [junit4]   2> 607940 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\control-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\control-001\cores\collection1\data\]
>   [junit4]   2> 607956 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
>   [junit4]   2> 607956 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
>   [junit4]   2> 607957 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
>   [junit4]   2> 607957 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
>   [junit4]   2> 607957 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51d172[collection1] main]
>   [junit4]   2> 607959 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
>   [junit4]   2> 607959 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
>   [junit4]   2> 607959 INFO  (searcherExecutor-2034-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51d172[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
>   [junit4]   2> 607959 INFO  (coreLoadExecutor-2033-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa c:control_collection   x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1557900786673909760
>   [junit4]   2> 607967 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
>   [junit4]   2> 607967 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa 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> 607967 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51083/mdh/a/collection1/
>   [junit4]   2> 607967 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
>   [junit4]   2> 607967 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51083/mdh/a/collection1/ has no replicas
>   [junit4]   2> 607973 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa 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:51083/mdh/a/collection1/ shard1
>   [junit4]   2> 608125 INFO  (coreZkRegister-2026-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa x:collection1 c:control_collection) [n:127.0.0.1:51083_mdh%2Fa c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
>   [junit4]   2> 608178 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
>   [junit4]   2> 608180 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51076/solr ready
>   [junit4]   2> 608180 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
>   [junit4]   2> 608180 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
>   [junit4]   2> 608776 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001\cores\collection1
>   [junit4]   2> 608779 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001
>   [junit4]   2> 608779 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
>   [junit4]   2> 608780 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c2d932{/mdh/a,null,AVAILABLE}
>   [junit4]   2> 608782 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@f20d24{HTTP/1.1,[http/1.1]}{127.0.0.1:51107}
>   [junit4]   2> 608783 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server Started @614399ms
>   [junit4]   2> 608783 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/mdh/a, hostPort=51107, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001\cores}
>   [junit4]   2> 608783 ERROR (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
>   [junit4]   2> 608783 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
>   [junit4]   2> 608783 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
>   [junit4]   2> 608783 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
>   [junit4]   2> 608783 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-29T22:46:08.584Z
>   [junit4]   2> 608787 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
>   [junit4]   2> 608787 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001\solr.xml
>   [junit4]   2> 608804 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51076/solr
>   [junit4]   2> 608808 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec6826580008, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 608819 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
>   [junit4]   2> 608826 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51107_mdh%2Fa
>   [junit4]   2> 608828 INFO  (zkCallback-742-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
>   [junit4]   2> 608828 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
>   [junit4]   2> 608828 INFO  (zkCallback-738-thread-2-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
>   [junit4]   2> 608990 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001\cores
>   [junit4]   2> 608990 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
>   [junit4]   2> 608994 INFO  (OverseerStateUpdate-97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
>   [junit4]   2> 609099 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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> 610009 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
>   [junit4]   2> 610027 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
>   [junit4]   2> 610066 WARN  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
>   [junit4]   2> 610067 WARN  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
>   [junit4]   2> 610067 WARN  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
>   [junit4]   2> 610068 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
>   [junit4]   2> 610073 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
>   [junit4]   2> 610073 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-1-001\cores\collection1\data\]
>   [junit4]   2> 610092 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
>   [junit4]   2> 610093 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
>   [junit4]   2> 610095 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
>   [junit4]   2> 610095 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
>   [junit4]   2> 610095 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e347bb[collection1] main]
>   [junit4]   2> 610098 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
>   [junit4]   2> 610098 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
>   [junit4]   2> 610099 INFO  (searcherExecutor-2044-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e347bb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
>   [junit4]   2> 610099 INFO  (coreLoadExecutor-2043-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1557900788917862400
>   [junit4]   2> 610109 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
>   [junit4]   2> 610109 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
>   [junit4]   2> 610109 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51107/mdh/a/collection1/
>   [junit4]   2> 610109 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
>   [junit4]   2> 610109 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51107/mdh/a/collection1/ has no replicas
>   [junit4]   2> 610117 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51107/mdh/a/collection1/ shard2
>   [junit4]   2> 610222 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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> 610270 INFO  (coreZkRegister-2038-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51107_mdh%2Fa c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
>   [junit4]   2> 610379 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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> 610791 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001\cores\collection1
>   [junit4]   2> 610794 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001
>   [junit4]   2> 610795 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
>   [junit4]   2> 610798 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4dee6f{/mdh/a,null,AVAILABLE}
>   [junit4]   2> 610799 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5911aa{HTTP/1.1,[http/1.1]}{127.0.0.1:51119}
>   [junit4]   2> 610799 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server Started @616416ms
>   [junit4]   2> 610799 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/mdh/a, hostPort=51119, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001\cores}
>   [junit4]   2> 610799 ERROR (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
>   [junit4]   2> 610800 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
>   [junit4]   2> 610800 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
>   [junit4]   2> 610800 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
>   [junit4]   2> 610800 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-29T22:46:10.601Z
>   [junit4]   2> 610809 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec682658000a, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 610810 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
>   [junit4]   2> 610810 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001\solr.xml
>   [junit4]   2> 610826 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51076/solr
>   [junit4]   2> 610830 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec682658000b, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 610840 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
>   [junit4]   2> 610845 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51119_mdh%2Fa
>   [junit4]   2> 610848 INFO  (zkCallback-742-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
>   [junit4]   2> 610849 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
>   [junit4]   2> 610849 INFO  (zkCallback-738-thread-2-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
>   [junit4]   2> 610850 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
>   [junit4]   2> 610897 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001\cores
>   [junit4]   2> 610897 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
>   [junit4]   2> 610902 INFO  (OverseerStateUpdate-97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
>   [junit4]   2> 611007 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [3])
>   [junit4]   2> 611007 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [3])
>   [junit4]   2> 611916 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
>   [junit4]   2> 611924 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
>   [junit4]   2> 611969 WARN  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
>   [junit4]   2> 611971 WARN  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
>   [junit4]   2> 611971 WARN  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
>   [junit4]   2> 611971 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
>   [junit4]   2> 611975 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
>   [junit4]   2> 611975 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-2-001\cores\collection1\data\]
>   [junit4]   2> 611991 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
>   [junit4]   2> 611991 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
>   [junit4]   2> 611992 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
>   [junit4]   2> 611992 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
>   [junit4]   2> 611993 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1999a08[collection1] main]
>   [junit4]   2> 611995 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
>   [junit4]   2> 611996 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
>   [junit4]   2> 611996 INFO  (coreLoadExecutor-2053-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1557900790907011072
>   [junit4]   2> 611998 INFO  (searcherExecutor-2054-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1999a08[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
>   [junit4]   2> 612005 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
>   [junit4]   2> 612005 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
>   [junit4]   2> 612005 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51119/mdh/a/collection1/
>   [junit4]   2> 612005 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
>   [junit4]   2> 612005 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51119/mdh/a/collection1/ has no replicas
>   [junit4]   2> 612009 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51119/mdh/a/collection1/ shard1
>   [junit4]   2> 612116 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [3])
>   [junit4]   2> 612117 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [3])
>   [junit4]   2> 612164 INFO  (coreZkRegister-2048-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51119_mdh%2Fa c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
>   [junit4]   2> 612272 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [3])
>   [junit4]   2> 612273 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [3])
>   [junit4]   2> 612697 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001\cores\collection1
>   [junit4]   2> 612702 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001
>   [junit4]   2> 612703 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
>   [junit4]   2> 612704 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@71f4f{/mdh/a,null,AVAILABLE}
>   [junit4]   2> 612705 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@17480d4{HTTP/1.1,[http/1.1]}{127.0.0.1:51131}
>   [junit4]   2> 612706 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server Started @618321ms
>   [junit4]   2> 612706 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/mdh/a, hostPort=51131, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001\cores}
>   [junit4]   2> 612706 ERROR (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
>   [junit4]   2> 612706 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
>   [junit4]   2> 612706 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
>   [junit4]   2> 612706 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
>   [junit4]   2> 612706 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-29T22:46:12.507Z
>   [junit4]   2> 612713 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec682658000d, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 612713 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
>   [junit4]   2> 612714 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001\solr.xml
>   [junit4]   2> 612730 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51076/solr
>   [junit4]   2> 612745 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51131_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
>   [junit4]   2> 612750 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51131_mdh%2Fa    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51131_mdh%2Fa
>   [junit4]   2> 612754 INFO  (zkCallback-742-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
>   [junit4]   2> 612754 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
>   [junit4]   2> 612756 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
>   [junit4]   2> 612757 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
>   [junit4]   2> 612757 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
>   [junit4]   2> 612854 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51131_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001\cores
>   [junit4]   2> 612854 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51131_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
>   [junit4]   2> 612859 INFO  (OverseerStateUpdate-97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
>   [junit4]   2> 612966 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [4])
>   [junit4]   2> 612966 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] 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: [4])
>   [junit4]   2> 612966 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [4])
>   [junit4]   2> 613884 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
>   [junit4]   2> 613892 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
>   [junit4]   2> 613932 WARN  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
>   [junit4]   2> 613933 WARN  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
>   [junit4]   2> 613933 WARN  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
>   [junit4]   2> 613934 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
>   [junit4]   2> 613938 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
>   [junit4]   2> 613939 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-3-001\cores\collection1\data\]
>   [junit4]   2> 613961 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
>   [junit4]   2> 613961 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
>   [junit4]   2> 613964 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
>   [junit4]   2> 613964 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
>   [junit4]   2> 613968 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cbbe[collection1] main]
>   [junit4]   2> 613970 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
>   [junit4]   2> 613971 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
>   [junit4]   2> 613971 INFO  (searcherExecutor-2064-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17cbbe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
>   [junit4]   2> 613971 INFO  (coreLoadExecutor-2063-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1557900792977948672
>   [junit4]   2> 613984 INFO  (coreZkRegister-2058-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
>   [junit4]   2> 613986 INFO  (updateExecutor-757-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
>   [junit4]   2> 613986 INFO  (recoveryExecutor-758-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
>   [junit4]   2> 613986 INFO  (recoveryExecutor-758-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
>   [junit4]   2> 613986 INFO  (recoveryExecutor-758-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
>   [junit4]   2> 613986 INFO  (recoveryExecutor-758-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
>   [junit4]   2> 613986 INFO  (recoveryExecutor-758-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:51107/mdh/a/collection1/] and I am [http://127.0.0.1:51131/mdh/a/collection1/]
>   [junit4]   2> 613993 INFO  (recoveryExecutor-758-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:51131_mdh%2Fa c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:51107/mdh/a]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:51131_mdh%252Fa&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
>   [junit4]   2> 613996 INFO  (qtp9234537-4642) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
>   [junit4]   2> 613997 INFO  (qtp9234537-4642) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard2 of collection1) have state: recovering
>   [junit4]   2> 613998 INFO  (qtp9234537-4642) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:51131_mdh%2Fa, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:51131/mdh/a","node_name":"127.0.0.1:51131_mdh%2Fa","state":"down"}
>   [junit4]   2> 614092 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [4])
>   [junit4]   2> 614092 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [4])
>   [junit4]   2> 614092 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] 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: [4])
>   [junit4]   2> 614661 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001\cores\collection1
>   [junit4]   2> 614664 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001
>   [junit4]   2> 614665 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
>   [junit4]   2> 614666 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@163a25f{/mdh/a,null,AVAILABLE}
>   [junit4]   2> 614667 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@161e845{HTTP/1.1,[http/1.1]}{127.0.0.1:51144}
>   [junit4]   2> 614667 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.Server Started @620283ms
>   [junit4]   2> 614667 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/mdh/a, hostPort=51144, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001\cores}
>   [junit4]   2> 614668 ERROR (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
>   [junit4]   2> 614670 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
>   [junit4]   2> 614670 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
>   [junit4]   2> 614670 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
>   [junit4]   2> 614670 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-29T22:46:14.471Z
>   [junit4]   2> 614677 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec6826580010, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 614677 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
>   [junit4]   2> 614677 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001\solr.xml
>   [junit4]   2> 614694 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51076/solr
>   [junit4]   2> 614699 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
>   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x159ec6826580011, likely client has closed socket
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
>   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 614712 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51144_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
>   [junit4]   2> 614720 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51144_mdh%2Fa    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51144_mdh%2Fa
>   [junit4]   2> 614724 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
>   [junit4]   2> 614724 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
>   [junit4]   2> 614725 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:51083_mdh%2Fa) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
>   [junit4]   2> 614726 INFO  (zkCallback-742-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
>   [junit4]   2> 614726 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
>   [junit4]   2> 614727 INFO  (zkCallback-767-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
>   [junit4]   2> 614842 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614843 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614843 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614845 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51144_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001\cores
>   [junit4]   2> 614845 INFO  (TEST-TestSQLHandler.doTest-seed#[C908CBE9EBEACE10]) [n:127.0.0.1:51144_mdh%2Fa    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
>   [junit4]   2> 614850 INFO  (OverseerStateUpdate-97368799029231620-127.0.0.1:51083_mdh%2Fa-n_0000000000) [n:127.0.0.1:51083_mdh%2Fa    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
>   [junit4]   2> 614962 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614962 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614963 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614963 INFO  (zkCallback-767-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 614999 INFO  (qtp9234537-4642) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:51131_mdh%2Fa, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:51131/mdh/a","node_name":"127.0.0.1:51131_mdh%2Fa","state":"recovering"}
>   [junit4]   2> 614999 INFO  (qtp9234537-4642) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
>   [junit4]   2> 614999 INFO  (qtp9234537-4642) [n:127.0.0.1:51107_mdh%2Fa    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:51131_mdh%252Fa&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1002
>   [junit4]   2> 615874 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
>   [junit4]   2> 615890 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
>   [junit4]   2> 616671 WARN  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
>   [junit4]   2> 616674 WARN  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
>   [junit4]   2> 616674 WARN  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
>   [junit4]   2> 616674 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
>   [junit4]   2> 616679 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
>   [junit4]   2> 616681 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSQLHandler_C908CBE9EBEACE10-001\shard-4-001\cores\collection1\data\]
>   [junit4]   2> 616705 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
>   [junit4]   2> 616705 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
>   [junit4]   2> 616707 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
>   [junit4]   2> 616707 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
>   [junit4]   2> 616710 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a0bc10[collection1] main]
>   [junit4]   2> 616711 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
>   [junit4]   2> 616712 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
>   [junit4]   2> 616712 INFO  (searcherExecutor-2074-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a0bc10[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
>   [junit4]   2> 616712 INFO  (coreLoadExecutor-2073-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa c:collection1   x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1557900795852095488
>   [junit4]   2> 616727 INFO  (coreZkRegister-2068-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 c:collection1) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
>   [junit4]   2> 616729 INFO  (updateExecutor-764-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
>   [junit4]   2> 616729 INFO  (recoveryExecutor-765-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
>   [junit4]   2> 616729 INFO  (recoveryExecutor-765-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
>   [junit4]   2> 616729 INFO  (recoveryExecutor-765-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
>   [junit4]   2> 616729 INFO  (recoveryExecutor-765-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
>   [junit4]   2> 616729 INFO  (recoveryExecutor-765-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:51144_mdh%2Fa c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:51119/mdh/a/collection1/] and I am [http://127.0.0.1:51144/mdh/a/collection1/]
>   [junit4]   2> 616734 INFO  (zkCallback-748-thread-1-processing-n:127.0.0.1:51107_mdh%2Fa) [n:127.0.0.1:51107_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 616734 INFO  (zkCallback-760-thread-1-processing-n:127.0.0.1:51131_mdh%2Fa) [n:127.0.0.1:51131_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 616734 INFO  (zkCallback-754-thread-1-processing-n:127.0.0.1:51119_mdh%2Fa) [n:127.0.0.1:51119_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 616735 INFO  (zkCallback-767-thread-1-processing-n:127.0.0.1:51144_mdh%2Fa) [n:127.0.0.1:51144_mdh%2Fa    ] 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: [5])
>   [junit4]   2> 616740 INFO  (recoveryExecutor-765-thread-1-processing-n:127.0.0.1:5
> 
> [...truncated too long message...]
> 
> getReplicationDetails(ReplicationHandler.java:929)
>   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:314)
>   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166)
>   [junit4]   2> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2402)
>   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
>   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464)
>   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
>   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296)
>   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
>   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
>   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
>   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
>   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
>   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
>   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
>   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
>   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
>   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
>   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
>   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
>   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
>   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
>   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
>   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
>   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
>   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
>   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
>   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
>   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
>   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
>   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
>   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> 852919 INFO  (qtp27778960-7336) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
>   [junit4]   2> 852920 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1a01839{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
>   [junit4]   2> 852920 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=25332278
>   [junit4]   2> 852922 INFO  (coreCloseExecutor-3247-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1339597
>   [junit4]   2> 852922 INFO  (coreCloseExecutor-3247-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
>   [junit4]   2> 852923 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
>   [junit4]   2> 852924 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@83fa48{/solr,null,UNAVAILABLE}
>   [junit4]   2> 852926 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.SolrTestCaseJ4 ###Ending doTestDetails
>   [junit4]   2> 852927 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1cfefc4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
>   [junit4]   2> 852927 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=32317482
>   [junit4]   2> 852927 INFO  (coreCloseExecutor-3249-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1d483b6
>   [junit4]   2> 852928 INFO  (coreCloseExecutor-3249-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
>   [junit4]   2> 852929 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
>   [junit4]   2> 852929 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9c0bfd{/solr,null,UNAVAILABLE}
>   [junit4]   2> 852931 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@efd1bf{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
>   [junit4]   2> 852931 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=6225463
>   [junit4]   2> 852932 INFO  (coreCloseExecutor-3251-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1ae9e72
>   [junit4]   2> 852932 INFO  (coreCloseExecutor-3251-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
>   [junit4]   2> 852933 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
>   [junit4]   2> 852933 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[C908CBE9EBEACE10]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a917e{/solr,null,UNAVAILABLE}
>   [junit4]   2> 852934 INFO  (SUITE-TestReplicationHandler-seed#[C908CBE9EBEACE10]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
>   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_C908CBE9EBEACE10-001
>   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=ar-OM, timezone=Australia/Broken_Hill
>   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_121 (32-bit)/cpus=3,threads=1,free=151130952,total=350486528
>   [junit4]   2> NOTE: All tests run in this JVM: [SystemInfoHandlerTest, MetricsHandlerTest, BadCopyFieldTest, SOLR749Test, TestSolrQueryParserResource, HttpPartitionTest, ZkControllerTest, HighlighterTest, UniqFieldsUpdateProcessorFactoryTest, TestNoOpRegenerator, TestImpersonationWithHadoopAuth, LeaderElectionTest, TestMiniSolrCloudClusterSSL, DocValuesMissingTest, TestElisionMultitermQuery, TestNamedUpdateProcessors, SmileWriterTest, HighlighterMaxOffsetTest, TestDeleteCollectionOnDownNodes, MoreLikeThisHandlerTest, TestSolrDeletionPolicy1, CoreAdminCreateDiscoverTest, DisMaxRequestHandlerTest, DocValuesTest, BaseCdcrDistributedZkTest, TestSolrQueryResponse, OverseerStatusTest, TestAuthenticationFramework, TestRandomDVFaceting, TestSolrXml, HdfsNNFailoverTest, SegmentsInfoRequestHandlerTest, TestGroupingSearch, TestDistribIDF, TestUpdate, SpatialRPTFieldTypeTest, TestHashPartitioner, TestComplexPhraseQParserPlugin, CurrencyFieldXmlFileTest, TestStressInPlaceUpdates, TestInitQParser, TestDynamicFieldCollectionResource, TermsComponentTest, RankQueryTest, TestRawResponseWriter, DirectSolrConnectionTest, TestRTGBase, TestXIncludeConfig, BJQParserTest, AssignTest, MigrateRouteKeyTest, MultiTermTest, TestSchemaManager, DistributedQueueTest, TestSmileRequest, TestManagedResourceStorage, TestBlobHandler, BlobRepositoryCloudTest, TestMergePolicyConfig, DirectUpdateHandlerTest, ConjunctionSolrSpellCheckerTest, TlogReplayBufferedWhileIndexingTest, TestGraphTermsQParserPlugin, TestBlendedInfixSuggestions, PolyFieldTest, ConcurrentDeleteAndCreateCollectionTest, ZkNodePropsTest, CollectionsAPIAsyncDistributedZkTest, TestSurroundQueryParser, ClassificationUpdateProcessorTest, BufferStoreTest, BigEndianAscendingWordSerializerTest, TestJavabinTupleStreamParser, VMParamsZkACLAndCredentialsProvidersTest, TestPartialUpdateDeduplication, TestSQLHandler, TestStandardQParsers, TestClassicSimilarityFactory, TestFaceting, ClassificationUpdateProcessorFactoryTest, FileUtilsTest, ShardRoutingTest, CloudExitableDirectoryReaderTest, DirectoryFactoryTest, ParsingFieldUpdateProcessorsTest, TestFieldCacheSanityChecker, TestExpandComponent, TestOrdValues, HdfsSyncSliceTest, TestJettySolrRunner, TestStressVersions, LeaderInitiatedRecoveryOnCommitTest, PeerSyncReplicationTest, DateFieldTest, TestCustomDocTransformer, DocumentAnalysisRequestHandlerTest, SampleTest, ReplicaListTransformerTest, TestReplicationHandler]
>   [junit4] Completed [246/684 (2!)] on J0 in 61.71s, 16 tests, 1 failure, 1 skipped <<< FAILURES!
> 
> [...truncated 56068 lines...]
> 
> 
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org


Mime
View raw message