Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19381/
Java: 64bit/jdk-9-ea+164 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestSQLHandler
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.TestSQLHandler:
1) Thread[id=15739, name=Connection evictor, state=TIMED_WAITING,
group=TGRP-TestSQLHandler] at
java.base@9-ea/java.lang.Thread.sleep(Native Method) at
app//org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
at java.base@9-ea/java.lang.Thread.run(Thread.java:844)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.handler.TestSQLHandler:
1) Thread[id=15739, name=Connection evictor, state=TIMED_WAITING,
group=TGRP-TestSQLHandler]
at java.base@9-ea/java.lang.Thread.sleep(Native Method)
at
app//org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
at java.base@9-ea/java.lang.Thread.run(Thread.java:844)
at __randomizedtesting.SeedInfo.seed([CDCCDAA9145909C3]:0)
FAILED: org.apache.solr.handler.TestSQLHandler.doTest
Error Message:
--> https://127.0.0.1:40882/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: --> https://127.0.0.1:40882/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([CDCCDAA9145909C3:6A88620D79E21A7A]:0)
at
org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:235)
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:563)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
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 12768 lines...]
[junit4] Suite: org.apache.solr.handler.TestSQLHandler
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/init-core-data-001
[junit4] 2> 1827605 WARN
(SUITE-TestSQLHandler-seed#[CDCCDAA9145909C3]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
[junit4] 2> 1827605 INFO
(SUITE-TestSQLHandler-seed#[CDCCDAA9145909C3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 1827607 INFO
(SUITE-TestSQLHandler-seed#[CDCCDAA9145909C3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1827608 INFO
(SUITE-TestSQLHandler-seed#[CDCCDAA9145909C3]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1827608 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1827609 INFO (Thread-3392) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1827609 INFO (Thread-3392) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1827709 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkTestServer start zk server on port:33576
[junit4] 2> 1827713 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-sql.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1827715 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema-sql.xml
to /configs/conf1/schema.xml
[junit4] 2> 1827717 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1827718 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1827721 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1827723 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1827725 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1827726 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1827728 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1827730 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1827732 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1828160 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/control-001/cores/collection1
[junit4] 2> 1828162 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 1828163 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6f4191c9{/,null,AVAILABLE}
[junit4] 2> 1828164 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5ac3aa74{SSL,[ssl,
http/1.1]}{127.0.0.1:40022}
[junit4] 2> 1828164 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
Started @1830259ms
[junit4] 2> 1828164 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/tempDir-001/control/data,
hostPort=40022,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/control-001/cores}
[junit4] 2> 1828165 ERROR
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1828165 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1828165 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1828165 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1828165 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-13T08:27:43.086332Z
[junit4] 2> 1828166 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1828166 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/control-001/solr.xml
[junit4] 2> 1828170 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1828174 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:33576/solr
[junit4] 2> 1828182 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.Overseer
Overseer (id=null) closing
[junit4] 2> 1828183 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40022_
[junit4] 2> 1828183 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.Overseer
Overseer (id=97784434689966085-127.0.0.1:40022_-n_0000000000) starting
[junit4] 2> 1828186 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40022_
[junit4] 2> 1828187 INFO (zkCallback-2447-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1828302 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1828305 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1828305 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1828306 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/control-001/cores
[junit4] 2> 1828306 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1828307 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1828309 INFO
(OverseerStateUpdate-97784434689966085-127.0.0.1:40022_-n_0000000000) [ ]
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1828316 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1828323 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1828370 WARN (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1828371 WARN (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 1828371 WARN (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 1828371 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1828371 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection, trusted=true
[junit4] 2> 1828371 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.core_node1' (registry
'solr.core.control_collection.shard1.core_node1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1828371 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1828371 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/control-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/control-001/cores/collection1/data/]
[junit4] 2> 1828520 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1828520 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1828521 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1828521 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1828522 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@30d38fbd[collection1] main]
[junit4] 2> 1828522 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1828523 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1828524 INFO (searcherExecutor-7003-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@30d38fbd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1828524 INFO (coreLoadExecutor-7002-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1564550955998904320
[junit4] 2> 1828528 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1828528 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1828528 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40022/collection1/
[junit4] 2> 1828528 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1828528 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.SyncStrategy https://127.0.0.1:40022/collection1/ has no replicas
[junit4] 2> 1828528 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1828529 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:40022/collection1/ shard1
[junit4] 2> 1828680 INFO (coreZkRegister-6995-thread-1) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1828820 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1828821 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33576/solr ready
[junit4] 2> 1828821 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.ChaosMonkey
monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1828821 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 1829278 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001/cores/collection1
[junit4] 2> 1829279 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001
[junit4] 2> 1829281 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 1829281 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@266a3885{/,null,AVAILABLE}
[junit4] 2> 1829282 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@25a9bc46{SSL,[ssl,
http/1.1]}{127.0.0.1:40882}
[junit4] 2> 1829282 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
Started @1831377ms
[junit4] 2> 1829282 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/tempDir-001/jetty1,
hostPort=40882,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001/cores}
[junit4] 2> 1829282 ERROR
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1829282 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1829282 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1829282 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1829283 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-13T08:27:44.204013Z
[junit4] 2> 1829284 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1829284 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001/solr.xml
[junit4] 2> 1829287 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1829291 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:33576/solr
[junit4] 2> 1829295 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1829296 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.Overseer
Overseer (id=null) closing
[junit4] 2> 1829297 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40882_
[junit4] 2> 1829297 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1829297 INFO (zkCallback-2447-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1829297 INFO (zkCallback-2451-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1829425 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1829429 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1829429 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1829430 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001/cores
[junit4] 2> 1829430 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1829431 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1829431 INFO
(OverseerStateUpdate-97784434689966085-127.0.0.1:40022_-n_0000000000) [ ]
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 1829533 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1830436 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1830442 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1830488 WARN (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1830489 WARN (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 1830489 WARN (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 1830490 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1830490 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1830490 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.core_node1' (registry
'solr.core.collection1.shard2.core_node1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1830490 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1830490 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 1830659 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1830659 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1830661 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1830661 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1830662 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@130086[collection1] main]
[junit4] 2> 1830662 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1830662 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1830663 INFO (searcherExecutor-7013-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@130086[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1830663 INFO (coreLoadExecutor-7012-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1564550958241808384
[junit4] 2> 1830667 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1830667 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1830667 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40882/collection1/
[junit4] 2> 1830667 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1830667 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.SyncStrategy https://127.0.0.1:40882/collection1/ has no replicas
[junit4] 2> 1830667 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1830668 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:40882/collection1/ shard2
[junit4] 2> 1830769 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1830819 INFO (coreZkRegister-7007-thread-1) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1830921 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1831390 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001/cores/collection1
[junit4] 2> 1831390 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001
[junit4] 2> 1831393 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 1831393 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1526b252{/,null,AVAILABLE}
[junit4] 2> 1831394 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@6dab3ddb{SSL,[ssl,
http/1.1]}{127.0.0.1:37672}
[junit4] 2> 1831394 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
Started @1833489ms
[junit4] 2> 1831394 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/tempDir-001/jetty2,
hostPort=37672,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001/cores}
[junit4] 2> 1831394 ERROR
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1831394 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1831395 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1831395 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1831395 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-13T08:27:46.316120Z
[junit4] 2> 1831396 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1831396 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001/solr.xml
[junit4] 2> 1831398 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1831402 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:33576/solr
[junit4] 2> 1831407 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1831407 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.Overseer
Overseer (id=null) closing
[junit4] 2> 1831409 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37672_
[junit4] 2> 1831410 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1831410 INFO (zkCallback-2451-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1831410 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1831410 INFO (zkCallback-2447-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1831546 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1831550 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1831550 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1831551 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001/cores
[junit4] 2> 1831551 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1831552 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1831553 INFO
(OverseerStateUpdate-97784434689966085-127.0.0.1:40022_-n_0000000000) [ ]
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1831654 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1831654 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1832558 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1832563 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1832611 WARN (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1832612 WARN (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 1832612 WARN (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 1832612 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1832612 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1832612 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.core_node2' (registry
'solr.core.collection1.shard1.core_node2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1832612 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1832612 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 1832769 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1832770 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1832770 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1832770 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1832771 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@31f767f3[collection1] main]
[junit4] 2> 1832772 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1832772 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1832773 INFO (searcherExecutor-7023-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@31f767f3[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1832773 INFO (coreLoadExecutor-7022-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1564550960454303744
[junit4] 2> 1832777 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1832777 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1832777 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37672/collection1/
[junit4] 2> 1832777 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1832777 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.SyncStrategy https://127.0.0.1:37672/collection1/ has no replicas
[junit4] 2> 1832777 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1832778 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:37672/collection1/ shard1
[junit4] 2> 1832879 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1832879 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1832929 INFO (coreZkRegister-7017-thread-1) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1833030 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1833030 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1833588 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001/cores/collection1
[junit4] 2> 1833589 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001
[junit4] 2> 1833591 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 1833592 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6b361227{/,null,AVAILABLE}
[junit4] 2> 1833593 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@7f115471{SSL,[ssl,
http/1.1]}{127.0.0.1:41114}
[junit4] 2> 1833593 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
Started @1835688ms
[junit4] 2> 1833593 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/tempDir-001/jetty3,
hostPort=41114,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001/cores}
[junit4] 2> 1833593 ERROR
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1833594 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1833594 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1833594 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1833594 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-13T08:27:48.515300Z
[junit4] 2> 1833596 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1833596 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001/solr.xml
[junit4] 2> 1833598 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1833603 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:33576/solr
[junit4] 2> 1833608 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1833609 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.Overseer
Overseer (id=null) closing
[junit4] 2> 1833610 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41114_
[junit4] 2> 1833611 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1833611 INFO (zkCallback-2447-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1833611 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1833611 INFO (zkCallback-2451-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1833611 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1833758 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1833761 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1833762 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1833763 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001/cores
[junit4] 2> 1833763 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1833764 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1833765 INFO
(OverseerStateUpdate-97784434689966085-127.0.0.1:40022_-n_0000000000) [ ]
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 1833866 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1833866 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1833866 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1834770 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1834777 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1834839 WARN (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1834840 WARN (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 1834840 WARN (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 1834840 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1834840 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1834841 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.core_node3' (registry
'solr.core.collection1.shard2.core_node3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1834841 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1834841 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 1835001 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1835001 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1835002 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1835002 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1835003 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1abda4e9[collection1] main]
[junit4] 2> 1835003 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1835003 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1835005 INFO (searcherExecutor-7033-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1abda4e9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1835005 INFO (coreLoadExecutor-7032-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1564550962794725376
[junit4] 2> 1835008 INFO (coreZkRegister-7027-thread-1) [ ]
o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 1835009 INFO (updateExecutor-2466-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1835009 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1835009 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1835009 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 1835009 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1835009 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering,
leader is [https://127.0.0.1:40882/collection1/] and I am
[https://127.0.0.1:41114/collection1/]
[junit4] 2> 1835011 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[https://127.0.0.1:40882]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:41114_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1835023 INFO (qtp65531387-15300) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1835023 INFO (qtp65531387-15300) [ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard2 of collection1) have state: recovering
[junit4] 2> 1835023 INFO (qtp65531387-15300) [ ]
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:41114_, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"https://127.0.0.1:41114","node_name":"127.0.0.1:41114_","state":"down"}
[junit4] 2> 1835111 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1835111 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1835111 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1835783 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001/cores/collection1
[junit4] 2> 1835783 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001
[junit4] 2> 1835785 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 1835786 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@c8ac86f{/,null,AVAILABLE}
[junit4] 2> 1835786 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3816e59b{SSL,[ssl,
http/1.1]}{127.0.0.1:33689}
[junit4] 2> 1835786 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.e.j.s.Server
Started @1837881ms
[junit4] 2> 1835786 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/tempDir-001/jetty4,
hostPort=33689,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001/cores}
[junit4] 2> 1835786 ERROR
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1835787 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1835787 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1835787 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1835787 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-13T08:27:50.708542Z
[junit4] 2> 1835789 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1835789 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001/solr.xml
[junit4] 2> 1835791 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1835796 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:33576/solr
[junit4] 2> 1835800 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1835801 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ] o.a.s.c.Overseer
Overseer (id=null) closing
[junit4] 2> 1835801 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33689_
[junit4] 2> 1835802 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1835802 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1835803 INFO (zkCallback-2447-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1835803 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1835802 INFO (zkCallback-2476-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1835803 INFO (zkCallback-2451-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1835937 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1835942 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1835942 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1835943 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001/cores
[junit4] 2> 1835943 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1835944 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1835945 INFO
(OverseerStateUpdate-97784434689966085-127.0.0.1:40022_-n_0000000000) [ ]
o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1836024 INFO (qtp65531387-15300) [ ]
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:41114_, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"https://127.0.0.1:41114","node_name":"127.0.0.1:41114_","state":"recovering"}
[junit4] 2> 1836024 INFO (qtp65531387-15300) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1836025 INFO (qtp65531387-15300) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:41114_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1001
[junit4] 2> 1836046 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836046 INFO (zkCallback-2476-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836046 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836046 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836526 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to PeerSync from
[https://127.0.0.1:40882/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 1836526 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.u.PeerSync PeerSync: core=collection1 url=https://127.0.0.1:41114 START
replicas=[https://127.0.0.1:40882/collection1/] nUpdates=100
[junit4] 2> 1836529 INFO (qtp65531387-15299) [ ]
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> 1836529 INFO (qtp65531387-15299) [ ] o.a.s.c.S.Request
[collection1] webapp= path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-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> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1836530 INFO (recoveryExecutor-2467-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1836632 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836632 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836632 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836632 INFO (zkCallback-2476-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1836950 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1836956 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1837001 WARN (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1837002 WARN (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 1837002 WARN (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 1837002 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1837002 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1837003 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.core_node4' (registry
'solr.core.collection1.shard1.core_node4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@dc0ec28
[junit4] 2> 1837003 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1837003 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 1837191 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1837191 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1837192 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1837192 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1837193 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@5b0f223e[collection1] main]
[junit4] 2> 1837194 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1837194 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1837195 INFO (searcherExecutor-7043-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5b0f223e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1837195 INFO (coreLoadExecutor-7042-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1564550965091106816
[junit4] 2> 1837198 INFO (coreZkRegister-7037-thread-1) [ ]
o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 1837198 INFO (updateExecutor-2473-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1837198 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1837198 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1837198 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 1837198 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1837199 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering,
leader is [https://127.0.0.1:37672/collection1/] and I am
[https://127.0.0.1:33689/collection1/]
[junit4] 2> 1837202 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[https://127.0.0.1:37672]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:33689_&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1837213 INFO (qtp488753920-15328) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1837213 INFO (qtp488753920-15328) [ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 1837213 INFO (qtp488753920-15328) [ ]
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:33689_, coreNodeName=core_node4,
onlyIfActiveCheckResult=false, nodeProps:
core_node4:{"core":"collection1","base_url":"https://127.0.0.1:33689","node_name":"127.0.0.1:33689_","state":"down"}
[junit4] 2> 1837301 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1837302 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1837302 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1837302 INFO (zkCallback-2476-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1837484 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting doTest
[junit4] 2> 1837485 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):330
[junit4] 2> 1838213 INFO (qtp488753920-15328) [ ]
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:33689_, coreNodeName=core_node4,
onlyIfActiveCheckResult=false, nodeProps:
core_node4:{"core":"collection1","base_url":"https://127.0.0.1:33689","node_name":"127.0.0.1:33689_","state":"recovering"}
[junit4] 2> 1838213 INFO (qtp488753920-15328) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node4, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1838213 INFO (qtp488753920-15328) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:33689_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 1838714 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to PeerSync from
[https://127.0.0.1:37672/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 1838715 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.u.PeerSync PeerSync: core=collection1 url=https://127.0.0.1:33689 START
replicas=[https://127.0.0.1:37672/collection1/] nUpdates=100
[junit4] 2> 1838718 INFO (qtp488753920-15326) [ ]
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> 1838718 INFO (qtp488753920-15326) [ ] o.a.s.c.S.Request
[collection1] webapp= path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-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> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1838718 INFO (recoveryExecutor-2474-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1838844 INFO (zkCallback-2476-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1838844 INFO (zkCallback-2463-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1838844 INFO (zkCallback-2469-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1838844 INFO (zkCallback-2457-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1839486 INFO
(TEST-TestSQLHandler.doTest-seed#[CDCCDAA9145909C3]) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 1839501 INFO (qtp1260888757-15257) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1564550967508074496)} 0 1
[junit4] 2> 1839521 INFO (qtp2017225799-15349) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&_version_=-1564550967512268800&distrib.from=https://127.0.0.1:40882/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1564550967512268800)} 0 1
[junit4] 2> 1839522 INFO (qtp1611587185-15391) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&_version_=-1564550967515414528&distrib.from=https://127.0.0.1:37672/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1564550967515414528)} 0 0
[junit4] 2> 1839523 INFO (qtp488753920-15326) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:40882/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1564550967515414528)} 0 16
[junit4] 2> 1839523 INFO (qtp65531387-15299) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1564550967512268800)} 0 19
[junit4] 2> 1839524 INFO (qtp1260888757-15258) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1839524 INFO (qtp1260888757-15258) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1839524 INFO (qtp1260888757-15258) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1839524 INFO (qtp1260888757-15258) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 0
[junit4] 2> 1839524 INFO (qtp488753920-15323) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1839525 INFO (qtp488753920-15323) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1839525 INFO (qtp2017225799-15354) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1839525 INFO (qtp2017225799-15354) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1839525 INFO (qtp488753920-15323) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1839525 INFO (qtp2017225799-15354) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1839525 INFO (qtp488753920-15323) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40882/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1839525 INFO (qtp2017225799-15354) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40882/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1839530 INFO (qtp1611587185-15391) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1839530 INFO (qtp65531387-15296) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1839531 INFO (qtp65531387-15296) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1839531 INFO (qtp1611587185-15391) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1839531 INFO (qtp65531387-15296) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1839531 INFO (qtp1611587185-15391) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1839531 INFO (qtp65531387-15296) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40882/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1839531 INFO (qtp1611587185-15391) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40882/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1839531 INFO (qtp65531387-15295) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={_stateVer_=collection1:12&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 7
[junit4] 2> 1839534 INFO (qtp1260888757-15259) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2&CONTROL=TRUE}{add=[1 (1564550967541628928)]} 0 2
[junit4] 2> 1839538 INFO (qtp1611587185-15384) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37672/collection1/&wt=javabin&version=2}{add=[1
(1564550967545823232)]} 0 0
[junit4] 2> 1839538 INFO (qtp488753920-15324) [ ] o.a.s.u.p.LogUpdate
[...truncated too long message...]
> 23 /solr/overseer/collection-queue-work
[junit4] 2> 10 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSQLHandler
-Dtests.method=doTest -Dtests.seed=CDCCDAA9145909C3 -Dtests.multiplier=3
-Dtests.slow=true -Dtests.locale=az-AZ -Dtests.timezone=Asia/Samarkand
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 28.3s J2 | TestSQLHandler.doTest <<<
[junit4] > Throwable #1: java.io.IOException: -->
https://127.0.0.1:40882/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([CDCCDAA9145909C3:6A88620D79E21A7A]:0)
[junit4] > at
org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:235)
[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:563)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1855953 INFO
(SUITE-TestSQLHandler-seed#[CDCCDAA9145909C3]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestSQLHandler_CDCCDAA9145909C3-001
[junit4] 2> Apr 13, 2017 8:28:10 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> Apr 13, 2017 8:28:30 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.handler.TestSQLHandler:
[junit4] 2> 1) Thread[id=15739, name=Connection evictor,
state=TIMED_WAITING, group=TGRP-TestSQLHandler]
[junit4] 2> at java.base@9-ea/java.lang.Thread.sleep(Native Method)
[junit4] 2> at
app//org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
[junit4] 2> at java.base@9-ea/java.lang.Thread.run(Thread.java:844)
[junit4] 2> Apr 13, 2017 8:28:30 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=15739, name=Connection evictor,
state=TIMED_WAITING, group=TGRP-TestSQLHandler]
[junit4] 2> Apr 13, 2017 8:28:30 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=SimpleText,
sim=RandomSimilarity(queryNorm=false): {}, locale=az-AZ, timezone=Asia/Samarkand
[junit4] 2> NOTE: Linux 4.4.0-72-generic amd64/Oracle Corporation 9-ea
(64-bit)/cpus=12,threads=1,free=222346992,total=486014976
[junit4] 2> NOTE: All tests run in this JVM: [UpdateParamsTest,
TestTolerantSearch, SolrCloudReportersTest, TestDistribIDF, TestCollectionAPI,
BinaryUpdateRequestHandlerTest, TestFreeTextSuggestions,
TestJsonFacetRefinement, LukeRequestHandlerTest, TestFieldCacheVsDocValues,
FullSolrCloudDistribCmdsTest, TestDistributedStatsComponentCardinality,
TestGeoJSONResponseWriter, TestConfig, TestPointFields, TestCollectionAPIs,
TestUtils, TestAddFieldRealTimeGet, TestCSVResponseWriter, JvmMetricsTest,
OpenExchangeRatesOrgProviderTest, StatelessScriptUpdateProcessorFactoryTest,
TestSolrCloudWithKerberosAlt, TestRuleBasedAuthorizationPlugin,
MBeansHandlerTest, ResponseHeaderTest, TestSmileRequest,
CurrencyFieldXmlFileTest, TestFieldCacheReopen, BasicDistributedZk2Test,
OverseerModifyCollectionTest, SaslZkACLProviderTest, TestInPlaceUpdatesDistrib,
QueryResultKeyTest, CdcrReplicationDistributedZkTest, BlockDirectoryTest,
TestCloudPseudoReturnFields, CreateCollectionCleanupTest,
AsyncCallRequestStatusResponseTest, RestartWhileUpdatingTest, StressHdfsTest,
DistributedDebugComponentTest, MultiThreadedOCPTest, RecoveryZkTest,
AddBlockUpdateTest, ParsingFieldUpdateProcessorsTest, SpatialRPTFieldTypeTest,
TestMiniSolrCloudCluster, CachingDirectoryFactoryTest, RequestHandlersTest,
TestReload, CdcrVersionReplicationTest, EchoParamsTest,
TestClassicSimilarityFactory, SolrPluginUtilsTest,
TestRequestStatusCollectionAPI, TestSolrQueryParser, ZkStateWriterTest,
TestOnReconnectListenerSupport, TestDocBasedVersionConstraints,
SolrMetricReporterTest, TestPerFieldSimilarityWithDefaultOverride,
PKIAuthenticationIntegrationTest, SolrCoreCheckLockOnStartupTest,
TestBinaryResponseWriter, TestNumericTerms32,
DistributedQueryComponentOptimizationTest, CollectionTooManyReplicasTest,
TestWriterPerf, TestPhraseSuggestions, TestQuerySenderNoQuery, EnumFieldTest,
TestSolrDeletionPolicy1, FieldAnalysisRequestHandlerTest, TestLRUStatsCache,
SuggesterTest, OverseerTaskQueueTest, TestMergePolicyConfig,
LeaderElectionTest, StatsComponentTest, LeaderFailureAfterFreshStartTest,
VMParamsZkACLAndCredentialsProvidersTest, TestPushWriter, HttpPartitionTest,
TestGroupingSearch, WrapperMergePolicyFactoryTest, TestManagedResourceStorage,
OutOfBoxZkACLAndCredentialsProvidersTest, TestReversedWildcardFilterFactory,
SpatialHeatmapFacetsTest, TestAnalyzeInfixSuggestions, TestClassNameShortening,
TestOverriddenPrefixQueryForCustomFieldType, DirectUpdateHandlerTest,
TestIndexingPerformance, TestJsonRequest, TestStressReorder,
HdfsRestartWhileUpdatingTest, TemplateUpdateProcessorTest,
TestConfigSetsAPIZkFailure, CloneFieldUpdateProcessorFactoryTest,
TestTestInjection, TestTrieFacet, ClusterStateTest, FullHLLTest,
ChangedSchemaMergeTest, DebugComponentTest,
ConcurrentDeleteAndCreateCollectionTest, TestEmbeddedSolrServerSchemaAPI,
TestShortCircuitedRequests, TestTrackingShardHandlerFactory,
RemoteQueryErrorTest, TestNRTOpen, TestDocSet,
BigEndianAscendingWordSerializerTest, BasicDistributedZkTest,
ChaosMonkeySafeLeaderTest, ShardRoutingTest, TestRandomFaceting,
ShardRoutingCustomTest, AutoCommitTest, TestRecovery, HardAutoCommitTest,
TestSolr4Spatial, SolrCoreTest, BadIndexSchemaTest, TestFiltering,
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterTSTTest,
SpatialFilterTest, NoCacheHeaderTest, FieldMutatingUpdateProcessorTest,
DocValuesMultiTest, IndexBasedSpellCheckerTest, DirectSolrSpellCheckerTest,
TestOmitPositions, MoreLikeThisHandlerTest, IndexSchemaRuntimeFieldTest,
UpdateRequestProcessorFactoryTest, UniqFieldsUpdateProcessorFactoryTest,
TestPHPSerializedResponseWriter, TestComponentsName, SearchHandlerTest,
CopyFieldTest, SampleTest, NumericFieldsTest, TestElisionMultitermQuery,
ExternalFileFieldSortTest, TestSolrCoreProperties, TestLuceneMatchVersion,
TestCodecSupport, TimeZoneUtilsTest, TestFastOutputStream, TestFastLRUCache,
ChaosMonkeyNothingIsSafeTest, ConfigSetsAPITest, CustomCollectionTest,
DistributedVersionInfoTest, OverseerStatusTest, PeerSyncReplicationTest,
RecoveryAfterSoftCommitTest, SharedFSAutoReplicaFailoverTest,
SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest,
TestAuthenticationFramework, TestDistribDocBasedVersion,
TestLeaderInitiatedRecoveryThread, TestRandomFlRTGCloud, TestReplicaProperties,
TestSegmentSorting, TestStressInPlaceUpdates, HdfsChaosMonkeyNothingIsSafeTest,
HdfsCollectionsAPIDistributedZkTest, HdfsNNFailoverTest, HdfsRecoverLeaseTest,
HdfsTlogReplayBufferedWhileIndexingTest, HdfsWriteToMultipleCollectionsTest,
RuleEngineTest, RulesTest, TestImplicitCoreProperties, TestInfoStreamLogging,
TestInitParams, TestReloadAndDeleteDocs, TestSolrConfigHandler, TestSolrXml,
TestSolrCloudSnapshots, TestBlobHandler, TestReqParamsAPI, TestSQLHandler]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSQLHandler
-Dtests.seed=CDCCDAA9145909C3 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=az-AZ -Dtests.timezone=Asia/Samarkand -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | TestSQLHandler (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.handler.TestSQLHandler:
[junit4] > 1) Thread[id=15739, name=Connection evictor,
state=TIMED_WAITING, group=TGRP-TestSQLHandler]
[junit4] > at java.base@9-ea/java.lang.Thread.sleep(Native Method)
[junit4] > at
app//org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
[junit4] > at java.base@9-ea/java.lang.Thread.run(Thread.java:844)
[junit4] > at
__randomizedtesting.SeedInfo.seed([CDCCDAA9145909C3]:0)
[junit4] Completed [591/702 (1!)] on J2 in 48.40s, 1 test, 2 errors <<<
FAILURES!
[...truncated 36555 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
