Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/1006/
Java: 64bit/jdk-9-ea+173 -XX:+UseCompressedOops -XX:+UseParallelGC

3 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithDelegationTokens

Error Message:
SOLR-10951: Hadoop does not work on Java 9

Stack Trace:
com.carrotsearch.randomizedtesting.InternalAssumptionViolatedException: 
SOLR-10951: Hadoop does not work on Java 9
        at __randomizedtesting.SeedInfo.seed([ED6685D562614691]:0)
        at 
com.carrotsearch.randomizedtesting.RandomizedTest.assumeTrue(RandomizedTest.java:675)
        at 
com.carrotsearch.randomizedtesting.RandomizedTest.assumeFalse(RandomizedTest.java:683)
        at 
org.apache.lucene.util.LuceneTestCase.assumeFalse(LuceneTestCase.java:867)
        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.startup(TestSolrCloudWithDelegationTokens.java:67)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:847)
        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 
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.base/java.lang.Thread.run(Thread.java:844)


FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithDelegationTokens

Error Message:


Stack Trace:
java.lang.NullPointerException
        at __randomizedtesting.SeedInfo.seed([ED6685D562614691]:0)
        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.shutdown(TestSolrCloudWithDelegationTokens.java:90)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
        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 
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.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.handler.TestSQLHandler.doTest

Error Message:
--> http://127.0.0.1:56776/hc/collection1:Failed to execute sqlQuery 'select 
str_s, count(*), sum(field_i), min(field_i), max(field_i), avg(field_i) from 
collection1 where text='XXXX' group by str_s order by sum(field_i) asc limit 2' 
against JDBC connection 'jdbc:calcitesolr:'. Error while executing SQL "select 
str_s, count(*), sum(field_i), min(field_i), max(field_i), avg(field_i) from 
collection1 where text='XXXX' group by str_s order by sum(field_i) asc limit 
2": From line 1, column 39 to line 1, column 50: No match found for function 
signature min(<NUMERIC>)

Stack Trace:
java.io.IOException: --> http://127.0.0.1:56776/hc/collection1:Failed to 
execute sqlQuery 'select str_s, count(*), sum(field_i), min(field_i), 
max(field_i), avg(field_i) from collection1 where text='XXXX' group by str_s 
order by sum(field_i) asc limit 2' against JDBC connection 'jdbc:calcitesolr:'.
Error while executing SQL "select str_s, count(*), sum(field_i), min(field_i), 
max(field_i), avg(field_i) from collection1 where text='XXXX' group by str_s 
order by sum(field_i) asc limit 2": From line 1, column 39 to line 1, column 
50: No match found for function signature min(<NUMERIC>)
        at 
__randomizedtesting.SeedInfo.seed([ED6685D562614691:4A223D710FDA5528]:0)
        at 
org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:233)
        at 
org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:2527)
        at 
org.apache.solr.handler.TestSQLHandler.testBasicGrouping(TestSQLHandler.java:676)
        at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:90)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        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:992)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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 
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.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 11656 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_ED6685D562614691-001\init-core-data-001
   [junit4]   2> 673947 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 673948 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
   [junit4]   2> 673948 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 673948 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1937, maxMBSortInHeap=6.244682632426537, 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=en-PR, 
timezone=America/Godthab
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=3,threads=1,free=191851032,total=360710144
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrIndexConfig, 
TestClusterStateMutator, TestCorePropertiesReload, ConnectionReuseTest, 
JSONWriterTest, TestFieldTypeCollectionResource, TestGraphMLResponseWriter, 
TestXIncludeConfig, TestStressInPlaceUpdates, 
TestReversedWildcardFilterFactory, CreateCollectionCleanupTest, 
DistribCursorPagingTest, TestCloudSchemaless, TestIndexSearcher, 
SecurityConfHandlerTest, TestShardHandlerFactory, DocValuesMissingTest, 
AnalyticsQueryTest, TestCrossCoreJoin, TestFilteredDocIdSet, TestFastLRUCache, 
TestQueryUtils, TestFieldCacheReopen, TestUseDocValuesAsStored2, 
TestManagedResource, SynonymTokenizerTest, TestSolrFieldCacheMBean, 
TestCSVLoader, TestConfigSetsAPIZkFailure, CoreAdminCreateDiscoverTest, 
TestNumericTerms64, PolyFieldTest, TestLocalFSCloudBackupRestore, 
TestHighFrequencyDictionaryFactory, TestIBSimilarityFactory, 
ConjunctionSolrSpellCheckerTest, TestRebalanceLeaders, TestRawTransformer, 
TestShortCircuitedRequests, TestChildDocTransformer, DeleteShardTest, 
TestStressLucene, TestSolrJ, QueryEqualityTest, TestDistribIDF, TestCustomSort, 
IndexSchemaRuntimeFieldTest, CoreSorterTest, DistribJoinFromCollectionTest, 
SystemInfoHandlerTest, AutoCommitTest, TestRandomFlRTGCloud, SuggesterTSTTest, 
TestStressRecovery, BlockJoinFacetDistribTest, TestPivotHelperCode, 
HdfsTlogReplayBufferedWhileIndexingTest, 
StatelessScriptUpdateProcessorFactoryTest, TestValueSourceCache, 
ResponseLogComponentTest, TestBinaryField, 
UniqFieldsUpdateProcessorFactoryTest, BitVectorTest, 
TestPKIAuthenticationPlugin, OverseerTaskQueueTest, 
DistributedTermsComponentTest, TestConfigOverlay, TestSchemaSimilarityResource, 
TestCloudNestedDocsSort, SolrCoreCheckLockOnStartupTest, FacetPivotSmallTest, 
TestCloudJSONFacetJoinDomain, TestReRankQParserPlugin, TestMaxScoreQueryParser, 
TestIndexingPerformance, TestClassNameShortening, TestFastOutputStream, 
DateFieldTest, TestNamedUpdateProcessors, CollectionsAPISolrJTest, 
TestConfigSetProperties, ZkSolrClientTest, AliasIntegrationTest, 
DistributedVersionInfoTest, SpatialRPTFieldTypeTest, 
ChaosMonkeyNothingIsSafeTest, CdcrVersionReplicationTest, TestRTGBase, 
TestSolr4Spatial, SolrRequestParserTest, TestRandomFaceting, FullHLLTest, 
OverseerCollectionConfigSetProcessorTest, TestSolrCloudWithDelegationTokens]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.seed=ED6685D562614691 
-Dtests.slow=true -Dtests.locale=en-PR -Dtests.timezone=America/Godthab 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestSolrCloudWithDelegationTokens (suite) <<<
   [junit4]    > Assumption #1: SOLR-10951: Hadoop does not work on Java 
9Throwable #2: java.lang.NullPointerException
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([ED6685D562614691]:0)
   [junit4]    >        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.shutdown(TestSolrCloudWithDelegationTokens.java:90)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4] Completed [212/713 (1!)] on J1 in 0.22s, 0 tests, 1 error <<< 
FAILURES!

[...truncated 1230 lines...]
   [junit4] Suite: org.apache.solr.handler.TestSQLHandler
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\init-core-data-001
   [junit4]   2> 2037986 WARN  
(SUITE-TestSQLHandler-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
   [junit4]   2> 2037986 INFO  
(SUITE-TestSQLHandler-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2037989 INFO  
(SUITE-TestSQLHandler-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 2037989 INFO  
(SUITE-TestSQLHandler-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /hc/
   [junit4]   2> 2037991 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2037991 INFO  (Thread-4896) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2037991 INFO  (Thread-4896) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2037995 ERROR (Thread-4896) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2038091 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:56748
   [junit4]   2> 2038117 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-sql.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 2038123 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema-sql.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 2038128 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2038133 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 2038138 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 2038141 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 2038145 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2038148 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2038152 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2038155 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2038158 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractZkTestCase put 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 2038412 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\control-001\cores\collection1
   [junit4]   2> 2038413 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2038414 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@30187002{/hc,null,AVAILABLE}
   [junit4]   2> 2038415 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@753ec02b{HTTP/1.1,[http/1.1]}{127.0.0.1:56755}
   [junit4]   2> 2038415 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
Started @2040882ms
   [junit4]   2> 2038415 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/hc, 
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\tempDir-001/control/data,
 hostPort=56755, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\control-001\cores}
   [junit4]   2> 2038415 ERROR 
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2038416 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 2038416 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2038416 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2038416 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-28T00:19:16.796148500Z
   [junit4]   2> 2038418 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 0x15cec1101170002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 2038419 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2038419 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\control-001\solr.xml
   [junit4]   2> 2038422 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2038423 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.ZkContainer 
Zookeeper client=127.0.0.1:56748/solr
   [junit4]   2> 2038425 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 0x15cec1101170003, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 2038438 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.Overseer 
Overseer (id=null) closing
   [junit4]   2> 2038438 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56755_hc
   [junit4]   2> 2038439 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.Overseer 
Overseer (id=98212849672192004-127.0.0.1:56755_hc-n_0000000000) starting
   [junit4]   2> 2038445 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56755_hc
   [junit4]   2> 2038448 INFO  (zkCallback-3226-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2038483 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\control-001\cores
   [junit4]   2> 2038483 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2038484 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2038486 INFO  
(OverseerStateUpdate-98212849672192004-127.0.0.1:56755_hc-n_0000000000) [    ] 
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2039500 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2039512 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2039567 WARN  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 2039567 WARN  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 2039567 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 2039567 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection control_collection, trusted=true
   [junit4]   2> 2039568 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2039568 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\control-001\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\control-001\cores\collection1\data\]
   [junit4]   2> 2039606 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2039607 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2039608 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2039608 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2039608 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@325d60f7[collection1] main]
   [junit4]   2> 2039610 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2039610 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2039610 INFO  (coreLoadExecutor-9365-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1571405596448522240
   [junit4]   2> 2039610 INFO  (searcherExecutor-9366-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@325d60f7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2039616 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2039616 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2039616 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56755/hc/collection1/
   [junit4]   2> 2039616 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2039616 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.SyncStrategy http://127.0.0.1:56755/hc/collection1/ has no replicas
   [junit4]   2> 2039616 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2039619 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56755/hc/collection1/ shard1
   [junit4]   2> 2039771 INFO  (coreZkRegister-9358-thread-1) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2039999 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2040002 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56748/solr ready
   [junit4]   2> 2040002 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.ChaosMonkey 
monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2040250 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001\cores\collection1
   [junit4]   2> 2040251 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001
   [junit4]   2> 2040251 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2040252 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7fb38fae{/hc,null,AVAILABLE}
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@a7c6619{HTTP/1.1,[http/1.1]}{127.0.0.1:56776}
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
Started @2042721ms
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/hc, 
solrconfig=solrconfig.xml, 
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\tempDir-001/jetty1,
 hostPort=56776, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001\cores}
   [junit4]   2> 2040253 ERROR 
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2040253 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-28T00:19:18.633919800Z
   [junit4]   2> 2040257 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2040257 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001\solr.xml
   [junit4]   2> 2040260 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2040261 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.ZkContainer 
Zookeeper client=127.0.0.1:56748/solr
   [junit4]   2> 2040272 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2040274 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.Overseer 
Overseer (id=null) closing
   [junit4]   2> 2040276 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56776_hc
   [junit4]   2> 2040277 INFO  (zkCallback-3226-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2040277 INFO  (zkCallback-3230-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2040277 INFO  (zkCallback-3235-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2040320 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001\cores
   [junit4]   2> 2040321 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2040322 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2040323 INFO  
(OverseerStateUpdate-98212849672192004-127.0.0.1:56755_hc-n_0000000000) [    ] 
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 2041338 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2041354 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2041403 WARN  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 2041403 WARN  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 2041403 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 2041403 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 2041403 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2041403 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-1-001\cores\collection1\data\]
   [junit4]   2> 2041441 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2041441 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2041442 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2041442 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2041442 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@69775ddb[collection1] main]
   [junit4]   2> 2041444 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2041444 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2041445 INFO  (searcherExecutor-9376-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@69775ddb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2041445 INFO  (coreLoadExecutor-9375-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1571405598372659200
   [junit4]   2> 2041450 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2041450 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2041450 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56776/hc/collection1/
   [junit4]   2> 2041450 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2041450 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.SyncStrategy http://127.0.0.1:56776/hc/collection1/ has no replicas
   [junit4]   2> 2041450 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2041453 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56776/hc/collection1/ shard2
   [junit4]   2> 2041607 INFO  (coreZkRegister-9370-thread-1) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2042077 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001\cores\collection1
   [junit4]   2> 2042078 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001
   [junit4]   2> 2042079 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2042079 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@41d7eae6{/hc,null,AVAILABLE}
   [junit4]   2> 2042080 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3f716668{HTTP/1.1,[http/1.1]}{127.0.0.1:56788}
   [junit4]   2> 2042080 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
Started @2044548ms
   [junit4]   2> 2042080 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/hc, 
solrconfig=solrconfig.xml, 
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\tempDir-001/jetty2,
 hostPort=56788, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001\cores}
   [junit4]   2> 2042081 ERROR 
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2042081 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 2042081 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2042081 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2042081 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-28T00:19:20.461093500Z
   [junit4]   2> 2042084 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2042084 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001\solr.xml
   [junit4]   2> 2042087 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2042088 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.ZkContainer 
Zookeeper client=127.0.0.1:56748/solr
   [junit4]   2> 2042096 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2042098 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.Overseer 
Overseer (id=null) closing
   [junit4]   2> 2042100 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56788_hc
   [junit4]   2> 2042101 INFO  (zkCallback-3230-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2042101 INFO  (zkCallback-3226-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2042101 INFO  (zkCallback-3235-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2042102 INFO  (zkCallback-3241-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2042138 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001\cores
   [junit4]   2> 2042138 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2042139 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2042140 INFO  
(OverseerStateUpdate-98212849672192004-127.0.0.1:56755_hc-n_0000000000) [    ] 
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2043155 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2043177 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2043216 WARN  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 2043216 WARN  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 2043216 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 2043216 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 2043216 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2043216 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-2-001\cores\collection1\data\]
   [junit4]   2> 2043257 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2043257 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2043259 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2043259 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2043259 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@68bbcc0a[collection1] main]
   [junit4]   2> 2043261 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2043261 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2043261 INFO  (searcherExecutor-9386-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@68bbcc0a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2043262 INFO  (coreLoadExecutor-9385-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1571405600277921792
   [junit4]   2> 2043267 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2043267 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2043267 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56788/hc/collection1/
   [junit4]   2> 2043267 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2043267 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.SyncStrategy http://127.0.0.1:56788/hc/collection1/ has no replicas
   [junit4]   2> 2043267 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2043270 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56788/hc/collection1/ shard1
   [junit4]   2> 2043422 INFO  (coreZkRegister-9380-thread-1) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2043895 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001\cores\collection1
   [junit4]   2> 2043896 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001
   [junit4]   2> 2043896 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2043897 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@62f67797{/hc,null,AVAILABLE}
   [junit4]   2> 2043897 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3827ea43{HTTP/1.1,[http/1.1]}{127.0.0.1:56821}
   [junit4]   2> 2043898 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
Started @2046365ms
   [junit4]   2> 2043898 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/hc, 
solrconfig=solrconfig.xml, 
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\tempDir-001/jetty3,
 hostPort=56821, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001\cores}
   [junit4]   2> 2043898 ERROR 
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2043898 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 2043898 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2043898 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2043898 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-28T00:19:22.278401Z
   [junit4]   2> 2043901 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2043901 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001\solr.xml
   [junit4]   2> 2043905 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2043906 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.ZkContainer 
Zookeeper client=127.0.0.1:56748/solr
   [junit4]   2> 2043915 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 2043917 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.Overseer 
Overseer (id=null) closing
   [junit4]   2> 2043918 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56821_hc
   [junit4]   2> 2043919 INFO  (zkCallback-3226-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2043919 INFO  (zkCallback-3230-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2043920 INFO  (zkCallback-3235-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2043920 INFO  (zkCallback-3241-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2043920 INFO  (zkCallback-3247-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2043981 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001\cores
   [junit4]   2> 2043981 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2043983 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2043983 INFO  
(OverseerStateUpdate-98212849672192004-127.0.0.1:56755_hc-n_0000000000) [    ] 
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 2044991 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2044999 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2045047 WARN  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 2045047 WARN  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 2045048 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 2045048 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 2045048 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2045048 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-3-001\cores\collection1\data\]
   [junit4]   2> 2045087 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2045088 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2045089 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2045089 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2045089 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@41640ef6[collection1] main]
   [junit4]   2> 2045091 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2045091 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2045092 INFO  (searcherExecutor-9396-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@41640ef6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2045092 INFO  (coreLoadExecutor-9395-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1571405602196815872
   [junit4]   2> 2045094 INFO  (coreZkRegister-9390-thread-1) [    ] 
o.a.s.c.ZkController Core needs to recover:collection1
   [junit4]   2> 2045094 INFO  (updateExecutor-3244-thread-1) [    ] 
o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 2045094 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 2045094 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
   [junit4]   2> 2045094 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
   [junit4]   2> 2045094 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 2045094 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, 
leader is [http://127.0.0.1:56776/hc/collection1/] and I am 
[http://127.0.0.1:56821/hc/collection1/]
   [junit4]   2> 2045099 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Sending prep recovery command to 
[http://127.0.0.1:56776/hc]; [WaitForState: 
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:56821_hc&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 2045101 INFO  (qtp564621757-19980) [    ] 
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: 
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
   [junit4]   2> 2045102 INFO  (qtp564621757-19980) [    ] 
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 
(shard2 of collection1) have state: recovering
   [junit4]   2> 2045102 INFO  (qtp564621757-19980) [    ] 
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:56821_hc, coreNodeName=core_node3, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:56821/hc","node_name":"127.0.0.1:56821_hc","state":"down"}
   [junit4]   2> 2045738 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001\cores\collection1
   [junit4]   2> 2045739 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001
   [junit4]   2> 2045739 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2045740 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@133f089d{/hc,null,AVAILABLE}
   [junit4]   2> 2045741 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6fa1ad53{HTTP/1.1,[http/1.1]}{127.0.0.1:56852}
   [junit4]   2> 2045741 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.e.j.s.Server 
Started @2048209ms
   [junit4]   2> 2045741 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/hc, 
solrconfig=solrconfig.xml, 
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\tempDir-001/jetty4,
 hostPort=56852, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001\cores}
   [junit4]   2> 2045741 ERROR 
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2045742 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 2045742 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2045742 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2045742 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-28T00:19:24.122412600Z
   [junit4]   2> 2045746 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2045746 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001\solr.xml
   [junit4]   2> 2045751 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2045752 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.ZkContainer 
Zookeeper client=127.0.0.1:56748/solr
   [junit4]   2> 2045761 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2045763 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] o.a.s.c.Overseer 
Overseer (id=null) closing
   [junit4]   2> 2045767 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56852_hc
   [junit4]   2> 2045768 INFO  (zkCallback-3226-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2045769 INFO  (zkCallback-3230-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2045769 INFO  (zkCallback-3254-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2045769 INFO  (zkCallback-3247-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2045769 INFO  (zkCallback-3235-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2045769 INFO  (zkCallback-3241-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2045823 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001\cores
   [junit4]   2> 2045823 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2045825 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2045826 INFO  
(OverseerStateUpdate-98212849672192004-127.0.0.1:56755_hc-n_0000000000) [    ] 
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2046103 INFO  (qtp564621757-19980) [    ] 
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:56821_hc, coreNodeName=core_node3, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:56821/hc","node_name":"127.0.0.1:56821_hc","state":"recovering"}
   [junit4]   2> 2046103 INFO  (qtp564621757-19980) [    ] 
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, 
checkLive: true, onlyIfLeader: true for: 1 seconds.
   [junit4]   2> 2046103 INFO  (qtp564621757-19980) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={nodeName=127.0.0.1:56821_hc&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
 status=0 QTime=1000
   [junit4]   2> 2046603 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Attempting to PeerSync from 
[http://127.0.0.1:56776/hc/collection1/] - recoveringAfterStartup=[true]
   [junit4]   2> 2046603 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.PeerSync PeerSync: core=collection1 url=http://127.0.0.1:56821/hc START 
replicas=[http://127.0.0.1:56776/hc/collection1/] nUpdates=100
   [junit4]   2> 2046606 INFO  (qtp564621757-19982) [    ] 
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0 
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0, 
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
   [junit4]   2> 2046606 INFO  (qtp564621757-19982) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/hc path=/get 
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2046606 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0 
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0, 
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
   [junit4]   2> 2046606 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync 
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy No replay needed.
   [junit4]   2> 2046607 INFO  (recoveryExecutor-3245-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
   [junit4]   2> 2046838 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 2046863 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2046902 WARN  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 2046902 WARN  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 2046902 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 2046902 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 2046902 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2046902 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001\shard-4-001\cores\collection1\data\]
   [junit4]   2> 2046937 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2046937 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2046938 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2046938 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2046939 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4f8912f1[collection1] main]
   [junit4]   2> 2046940 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2046941 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2046941 INFO  (searcherExecutor-9406-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4f8912f1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2046941 INFO  (coreLoadExecutor-9405-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1571405604135632896
   [junit4]   2> 2046944 INFO  (coreZkRegister-9400-thread-1) [    ] 
o.a.s.c.ZkController Core needs to recover:collection1
   [junit4]   2> 2046944 INFO  (updateExecutor-3251-thread-1) [    ] 
o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 2046944 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 2046944 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
   [junit4]   2> 2046944 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
   [junit4]   2> 2046944 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 2046944 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, 
leader is [http://127.0.0.1:56788/hc/collection1/] and I am 
[http://127.0.0.1:56852/hc/collection1/]
   [junit4]   2> 2046946 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Sending prep recovery command to 
[http://127.0.0.1:56788/hc]; [WaitForState: 
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:56852_hc&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 2046948 INFO  (qtp254621434-20009) [    ] 
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state: 
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
   [junit4]   2> 2046949 INFO  (qtp254621434-20009) [    ] 
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 
(shard1 of collection1) have state: recovering
   [junit4]   2> 2046949 INFO  (qtp254621434-20009) [    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, 
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? 
true, live=true, checkLive=true, currentState=down, localState=active, 
nodeName=127.0.0.1:56852_hc, coreNodeName=core_node4, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node4:{"core":"collection1","base_url":"http://127.0.0.1:56852/hc","node_name":"127.0.0.1:56852_hc","state":"down"}
   [junit4]   2> 2047333 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting doTest
   [junit4]   2> 2047333 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):330
   [junit4]   2> 2047950 INFO  (qtp254621434-20009) [    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, 
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? 
true, live=true, checkLive=true, currentState=recovering, localState=active, 
nodeName=127.0.0.1:56852_hc, coreNodeName=core_node4, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node4:{"core":"collection1","base_url":"http://127.0.0.1:56852/hc","node_name":"127.0.0.1:56852_hc","state":"recovering"}
   [junit4]   2> 2047950 INFO  (qtp254621434-20009) [    ] 
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node4, state: recovering, 
checkLive: true, onlyIfLeader: true for: 1 seconds.
   [junit4]   2> 2047950 INFO  (qtp254621434-20009) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={nodeName=127.0.0.1:56852_hc&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
 status=0 QTime=1001
   [junit4]   2> 2048450 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Attempting to PeerSync from 
[http://127.0.0.1:56788/hc/collection1/] - recoveringAfterStartup=[true]
   [junit4]   2> 2048450 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.PeerSync PeerSync: core=collection1 url=http://127.0.0.1:56852/hc START 
replicas=[http://127.0.0.1:56788/hc/collection1/] nUpdates=100
   [junit4]   2> 2048452 INFO  (qtp254621434-20012) [    ] 
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0 
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0, 
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
   [junit4]   2> 2048452 INFO  (qtp254621434-20012) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/hc path=/get 
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2048452 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0 
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0, 
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
   [junit4]   2> 2048452 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync 
   [junit4]   2> 2048452 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2048452 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2048452 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2048453 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
   [junit4]   2> 2048453 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
   [junit4]   2> 2048453 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy No replay needed.
   [junit4]   2> 2048453 INFO  (recoveryExecutor-3252-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
   [junit4]   2> 2049335 INFO  
(TEST-TestSQLHandler.doTest-seed#[ED6685D562614691]) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 2049341 INFO  (qtp814469590-19948) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1571405606649069568)} 0 3
   [junit4]   2> 2049366 INFO  (qtp1705870127-20069) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&_version_=-1571405606656409600&distrib.from=http://127.0.0.1:56788/hc/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1571405606656409600)} 0 5
   [junit4]   2> 2049369 INFO  (qtp1571649081-20037) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&_version_=-1571405606662701056&distrib.from=http://127.0.0.1:56776/hc/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1571405606662701056)} 0 2
   [junit4]   2> 2049369 INFO  (qtp564621757-19983) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:56788/hc/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1571405606662701056)} 0 18
   [junit4]   2> 2049370 INFO  (qtp254621434-20011) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1571405606656409600)} 0 26
   [junit4]   2> 2049373 INFO  (qtp814469590-19949) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2049373 INFO  (qtp814469590-19949) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2049373 INFO  (qtp814469590-19949) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2049373 INFO  (qtp814469590-19949) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 2049377 INFO  (qtp254621434-20010) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2049377 INFO  (qtp254621434-20010) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2049377 INFO  (qtp254621434-20010) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2049378 INFO  (qtp564621757-19981) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2049378 INFO  (qtp564621757-19981) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2049379 INFO  (qtp564621757-19981) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2049379 INFO  (qtp1571649081-20038) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2049379 INFO  (qtp1571649081-20038) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2049379 INFO  (qtp1571649081-20038) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2049379 INFO  (qtp254621434-20010) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:56776/hc/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3
   [junit4]   2> 2049380 INFO  (qtp1705870127-20072) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2049380 INFO  (qtp1705870127-20072) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2049380 INFO  (qtp564621757-19981) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:56776/hc/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 2049381 INFO  (qtp1705870127-20072) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2049381 INFO  (qtp1571649081-20038) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:56776/hc/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 2049381 INFO  (qtp1705870127-20072) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:56776/hc/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2049382 INFO  (qtp564621757-19980) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 2049388 INFO  (qtp814469590-19950) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[1 (1571405606698352640)]} 0 4
   [junit4]   2> 2049397 INFO  (qtp1705870127-20074) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:56788/hc/collection1/&wt=javabin&version=2}{add=[1
 (1571405606706741248)]} 0 2
   [junit4]   2> 2049397 INFO  (qtp254621434-20007) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{add=[1 (1571405606706741248)]} 0 4
   [junit4]   2> 2049400 INFO  (qtp814469590-19947) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[2 (1571405606714081280)]} 0 0
   [junit4]   2> 2049405 INFO  (qtp1571649081-20040) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:56776/hc/collection1/&wt=javabin&version=2}{add=[2
 (1571405606715129856)]} 0 1
   [junit4]   2> 2049405 INFO  (qtp564621757-19978) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{add=[2 (1571405606715129856)]} 0 4
   [junit4]   2> 2049406 INFO  (qtp814469590-19951) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[3 (1571405606720372736)]} 0 0
   [junit4]   2> 2049409 INFO  (qtp1571649081-20041) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:56776/hc/collection1/&wt=javabin&version=2}{add=[3
 (1571405606721421312)]} 0 0
   [junit4]   2> 2049409 INFO  (qtp564621757-19982) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{add=[3 (1571405606721421312)]} 0 2
   [junit4]   2> 2049410 INFO  (qtp814469590-19946) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[4 (1571405606724567040)]} 0 0
   [junit4]   2> 2049412 INFO  (qtp1705870127-20075) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:56788/hc/collection1/&wt=javabin&version=2}{add=[4
 (1571405606725615616)]} 0 0
   [junit4]   2> 2049412 INFO  (qtp254621434-20013) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{add=[4 (1571405606725615616)]} 0 1
   [junit4]   2> 2049413 INFO  (qtp814469590-19944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[5 (1571405606727712768)]} 0 0
   [junit4]   2> 2049416 INFO  (qtp1571649081-20042) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:56776/hc/collection1/&wt=javabin&version=2}{add=[5
 (1571405606728761344)]} 0 0
   [junit4]   2> 2049416 INFO  (qtp564621757-19985) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{add=[5 (1571405606728761344)]} 0 2
   [junit4]   2> 2049417 INFO  (qtp814469590-19948) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[6 (1571405606731907072)]} 0 0
   [junit4]   2> 2049421 INFO  (qtp1571649081-20039) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:56776/hc/collection1/&wt=javabin&version=2}{add=[6
 (1571405606735052800)]} 0 0
   [junit4]   2> 2049421 INFO  (qtp564621757-19983) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2}{add=[6 (1571405606735052800)]} 0 1
   [junit4]   2> 2049423 INFO  (qtp814469590-19949) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/hc path=/update 
params={wt=javabin&version=2&CONTROL=TRUE}{add=[7 (1571405606738198528)]} 0 0
   [junit4]   2> 2049425 INFO  (qtp1571649081-20035) [    ] 
o.a.s.u.p.LogUpdateProcessorFactor

[...truncated too long message...]

   [junit4]   2>        65      /solr/live_nodes
   [junit4]   2>        65      /solr/collections
   [junit4]   2>        4       /solr/overseer/collection-queue-work
   [junit4]   2>        3       /solr/overseer/queue
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSQLHandler 
-Dtests.method=doTest -Dtests.seed=ED6685D562614691 -Dtests.slow=true 
-Dtests.locale=az-AZ -Dtests.timezone=Africa/Juba -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   21.9s J1 | TestSQLHandler.doTest <<<
   [junit4]    > Throwable #1: java.io.IOException: --> 
http://127.0.0.1:56776/hc/collection1:Failed to execute sqlQuery 'select str_s, 
count(*), sum(field_i), min(field_i), max(field_i), avg(field_i) from 
collection1 where text='XXXX' group by str_s order by sum(field_i) asc limit 2' 
against JDBC connection 'jdbc:calcitesolr:'.
   [junit4]    > Error while executing SQL "select str_s, count(*), 
sum(field_i), min(field_i), max(field_i), avg(field_i) from collection1 where 
text='XXXX' group by str_s order by sum(field_i) asc limit 2": From line 1, 
column 39 to line 1, column 50: No match found for function signature 
min(<NUMERIC>)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([ED6685D562614691:4A223D710FDA5528]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:233)
   [junit4]    >        at 
org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:2527)
   [junit4]    >        at 
org.apache.solr.handler.TestSQLHandler.testBasicGrouping(TestSQLHandler.java:676)
   [junit4]    >        at 
org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:90)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 2059863 INFO  
(SUITE-TestSQLHandler-seed#[ED6685D562614691]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSQLHandler_ED6685D562614691-001
   [junit4]   2> Jun 28, 2017 12:19:38 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene62, 
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=az-AZ, 
timezone=Africa/Juba
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=3,threads=1,free=232092232,total=523239424
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrIndexConfig, 
TestClusterStateMutator, TestCorePropertiesReload, ConnectionReuseTest, 
JSONWriterTest, TestFieldTypeCollectionResource, TestGraphMLResponseWriter, 
TestXIncludeConfig, TestStressInPlaceUpdates, 
TestReversedWildcardFilterFactory, CreateCollectionCleanupTest, 
DistribCursorPagingTest, TestCloudSchemaless, TestIndexSearcher, 
SecurityConfHandlerTest, TestShardHandlerFactory, DocValuesMissingTest, 
AnalyticsQueryTest, TestCrossCoreJoin, TestFilteredDocIdSet, TestFastLRUCache, 
TestQueryUtils, TestFieldCacheReopen, TestUseDocValuesAsStored2, 
TestManagedResource, SynonymTokenizerTest, TestSolrFieldCacheMBean, 
TestCSVLoader, TestConfigSetsAPIZkFailure, CoreAdminCreateDiscoverTest, 
TestNumericTerms64, PolyFieldTest, TestLocalFSCloudBackupRestore, 
TestHighFrequencyDictionaryFactory, TestIBSimilarityFactory, 
ConjunctionSolrSpellCheckerTest, TestRebalanceLeaders, TestRawTransformer, 
TestShortCircuitedRequests, TestChildDocTransformer, DeleteShardTest, 
TestStressLucene, TestSolrJ, QueryEqualityTest, TestDistribIDF, TestCustomSort, 
IndexSchemaRuntimeFieldTest, CoreSorterTest, DistribJoinFromCollectionTest, 
SystemInfoHandlerTest, AutoCommitTest, TestRandomFlRTGCloud, SuggesterTSTTest, 
TestStressRecovery, BlockJoinFacetDistribTest, TestPivotHelperCode, 
HdfsTlogReplayBufferedWhileIndexingTest, 
StatelessScriptUpdateProcessorFactoryTest, TestValueSourceCache, 
ResponseLogComponentTest, TestBinaryField, 
UniqFieldsUpdateProcessorFactoryTest, BitVectorTest, 
TestPKIAuthenticationPlugin, OverseerTaskQueueTest, 
DistributedTermsComponentTest, TestConfigOverlay, TestSchemaSimilarityResource, 
TestCloudNestedDocsSort, SolrCoreCheckLockOnStartupTest, FacetPivotSmallTest, 
TestCloudJSONFacetJoinDomain, TestReRankQParserPlugin, TestMaxScoreQueryParser, 
TestIndexingPerformance, TestClassNameShortening, TestFastOutputStream, 
DateFieldTest, TestNamedUpdateProcessors, CollectionsAPISolrJTest, 
TestConfigSetProperties, ZkSolrClientTest, AliasIntegrationTest, 
DistributedVersionInfoTest, SpatialRPTFieldTypeTest, 
ChaosMonkeyNothingIsSafeTest, CdcrVersionReplicationTest, TestRTGBase, 
TestSolr4Spatial, SolrRequestParserTest, TestRandomFaceting, FullHLLTest, 
OverseerCollectionConfigSetProcessorTest, TestSolrCloudWithDelegationTokens, 
RankQueryTest, BasicFunctionalityTest, TestHashQParserPlugin, 
ClassificationUpdateProcessorFactoryTest, TestSolrXml, TestQueryTypes, 
TestDistributedGrouping, BasicDistributedZk2Test, 
DistributedIntervalFacetingTest, TestRecovery, DistributedExpandComponentTest, 
TestScoreJoinQPNoScore, DeleteNodeTest, SolrMetricReporterTest, 
TestSerializedLuceneMatchVersion, TestXmlQParser, TestReqParamsAPI, 
SpellCheckComponentTest, TestRequestForwarding, TestSolrCoreSnapshots, 
TimeZoneUtilsTest, TestOmitPositions, TestFieldCacheVsDocValues, 
SpatialHeatmapFacetsTest, SuggestComponentTest, TestComponentsName, 
TestFiltering, TestSolrDeletionPolicy1, TestInPlaceUpdatesStandalone, 
DocExpirationUpdateProcessorFactoryTest, TestTolerantUpdateProcessorCloud, 
TestMinMaxOnMultiValuedField, TestSolrQueryParserResource, 
CdcrReplicationHandlerTest, CleanupOldIndexTest, TestHdfsCloudBackupRestore, 
ManagedSchemaRoundRobinCloudTest, TestMacroExpander, TestPushWriter, 
VersionInfoTest, TestExactStatsCache, TestPhraseSuggestions, TestTrie, 
TestClusterProperties, MultiTermTest, TestExportWriter, UtilsToolTest, 
HdfsSyncSliceTest, TestReloadDeadlock, TestFieldCollectionResource, 
TestJmxIntegration, TestComplexPhraseLeadingWildcard, AlternateDirectoryTest, 
WrapperMergePolicyFactoryTest, DistributedMLTComponentTest, 
TestManagedSynonymGraphFilterFactory, 
DistributedQueryComponentOptimizationTest, TestDeleteCollectionOnDownNodes, 
TestDynamicFieldCollectionResource, LeaderFailureAfterFreshStartTest, 
HdfsNNFailoverTest, SolrCmdDistributorTest, TestConfigSetsAPI, 
SolrGangliaReporterTest, TestRangeQuery, ConfigSetsAPITest, TestCharFilters, 
HdfsBasicDistributedZkTest, TestRemoteStreaming, TestSimpleQParserPlugin, 
ForceLeaderTest, TestWordDelimiterFilterFactory, 
DistributedQueryElevationComponentTest, TestLegacyFieldCache, JSONWriterTest, 
BlockJoinFacetSimpleTest, TestLeaderElectionZkExpiry, HLLUtilTest, 
BasicAuthIntegrationTest, CollectionReloadTest, 
BigEndianAscendingWordDeserializerTest, LukeRequestHandlerTest, 
ShardRoutingTest, BasicZkTest, ClusterStateUpdateTest, LeaderElectionTest, 
DistributedSpellCheckComponentTest, ZkControllerTest, TestStressReorder, 
TestJoin, TestCoreContainer, TestGroupingSearch, QueryElevationComponentTest, 
PeerSyncTest, DirectUpdateHandlerTest, TestBadConfig, 
ShowFileRequestHandlerTest, SimplePostToolTest, TestCoreDiscovery, 
SpellCheckCollatorTest, CoreAdminHandlerTest, FieldMutatingUpdateProcessorTest, 
DocValuesMultiTest, DebugComponentTest, IndexBasedSpellCheckerTest, 
StandardRequestHandlerTest, DirectSolrSpellCheckerTest, RequestHandlersTest, 
TermVectorComponentTest, FieldAnalysisRequestHandlerTest, 
RegexBoostProcessorTest, TestCollationField, ReturnFieldsTest, 
TestCSVResponseWriter, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, 
CSVRequestHandlerTest, SearchHandlerTest, HighlighterConfigTest, SOLR749Test, 
UpdateParamsTest, BadComponentTest, TestMergePolicyConfig, 
TestSolrDeletionPolicy2, SampleTest, TestSearchPerf, NumericFieldsTest, 
TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, 
ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, 
DirectSolrConnectionTest, SpellPossibilityIteratorTest, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, 
TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, 
TestBM25SimilarityFactory, ResourceLoaderTest, PluginInfoTest, 
PreAnalyzedFieldTest, PrimUtilsTest, DOMUtilTest, TestLRUCache, 
ZkNodePropsTest, FileUtilsTest, TestDistributedMissingSort, TestPathTrie, 
TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ActionThrottleTest, 
AsyncCallRequestStatusResponseTest, CdcrRequestHandlerTest, 
CollectionTooManyReplicasTest, CollectionsAPIAsyncDistributedZkTest, 
DocValuesNotIndexedTest, HttpPartitionTest, 
LeaderInitiatedRecoveryOnCommitTest, MissingSegmentRecoveryTest, 
MoveReplicaTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, 
RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, ReplaceNodeTest, 
SSLMigrationTest, SaslZkACLProviderTest, ShardSplitTest, TestSSLRandomization, 
TestSizeLimitedDistributedMap, BlobRepositoryCloudTest, 
CachingDirectoryFactoryTest, ConfigureRecoveryStrategyTest, 
ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, 
TestBackupRepositoryFactory, TestDynamicLoading, RequestLoggingTest, 
TestBlobHandler, TestSQLHandler]
   [junit4] Completed [576/713 (2!)] on J1 in 22.91s, 1 test, 1 error <<< 
FAILURES!

[...truncated 36891 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to