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-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org