Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Linux/47/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseSerialGC
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.client.solrj.request.TestV2Request
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.client.solrj.request.TestV2Request: 1) Thread[id=376,
name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestV2Request]
at java.lang.Thread.sleep(Native Method) at
org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.client.solrj.request.TestV2Request:
1) Thread[id=376, name=Connection evictor, state=TIMED_WAITING,
group=TGRP-TestV2Request]
at java.lang.Thread.sleep(Native Method)
at
org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([E94778272556A57A]:0)
FAILED: org.apache.solr.client.solrj.request.TestV2Request.testHttpSolrClient
Error Message:
Error from server at https://127.0.0.1:34903/solr: Could not fully remove
collection: test
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException:
Error from server at https://127.0.0.1:34903/solr: Could not fully remove
collection: test
at
__randomizedtesting.SeedInfo.seed([E94778272556A57A:315F600D8462B75D]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException.create(HttpSolrClient.java:829)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:620)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.client.solrj.request.TestV2Request.assertSuccess(TestV2Request.java:42)
at
org.apache.solr.client.solrj.request.TestV2Request.doTest(TestV2Request.java:91)
at
org.apache.solr.client.solrj.request.TestV2Request.testHttpSolrClient(TestV2Request.java:62)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.client.solrj.request.TestV2Request.testCloudSolrClient
Error Message:
Error from server at https://127.0.0.1:40411/solr: Could not fully create
collection: test
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException:
Error from server at https://127.0.0.1:40411/solr: Could not fully create
collection: test
at
__randomizedtesting.SeedInfo.seed([E94778272556A57A:70B1F39780E52D11]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException.create(HttpSolrClient.java:829)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:620)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.client.solrj.request.TestV2Request.assertSuccess(TestV2Request.java:42)
at
org.apache.solr.client.solrj.request.TestV2Request.doTest(TestV2Request.java:72)
at
org.apache.solr.client.solrj.request.TestV2Request.testCloudSolrClient(TestV2Request.java:68)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14945 lines...]
[junit4] Suite: org.apache.solr.client.solrj.request.TestV2Request
[junit4] 2> 19407 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/init-core-data-001
[junit4] 2> 19408 WARN
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 19409 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 19410 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 19508 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001
[junit4] 2> 19508 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 19512 INFO (Thread-50) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 19512 INFO (Thread-50) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 19517 ERROR (Thread-50) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 19612 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:35939
[junit4] 2> 19614 INFO (zkConnectionManagerCallback-75-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19631 INFO (jetty-launcher-72-thread-3) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 19631 INFO (jetty-launcher-72-thread-4) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 19631 INFO (jetty-launcher-72-thread-1) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 19631 INFO (jetty-launcher-72-thread-2) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 19633 INFO (jetty-launcher-72-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@25eb1df6{/solr,null,AVAILABLE}
[junit4] 2> 19635 INFO (jetty-launcher-72-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@53718fb2{/solr,null,AVAILABLE}
[junit4] 2> 19635 INFO (jetty-launcher-72-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@193d5104{/solr,null,AVAILABLE}
[junit4] 2> 19635 INFO (jetty-launcher-72-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@45bf8212{/solr,null,AVAILABLE}
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@2366c977{SSL,[ssl,
http/1.1]}{127.0.0.1:34903}
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@30e09f7a{SSL,[ssl,
http/1.1]}{127.0.0.1:45619}
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@54779932{SSL,[ssl,
http/1.1]}{127.0.0.1:35875}
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-3) [ ] o.e.j.s.Server
Started @21265ms
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ] o.e.j.s.Server
Started @21265ms
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ] o.e.j.s.Server
Started @21265ms
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45619}
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34903}
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35875}
[junit4] 2> 19646 ERROR (jetty-launcher-72-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 19646 ERROR (jetty-launcher-72-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-11T22:01:29.910Z
[junit4] 2> 19646 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-11T22:01:29.910Z
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@493f591d{SSL,[ssl,
http/1.1]}{127.0.0.1:40411}
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ] o.e.j.s.Server
Started @21266ms
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40411}
[junit4] 2> 19648 ERROR (jetty-launcher-72-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 19648 ERROR (jetty-launcher-72-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-11T22:01:29.912Z
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 19648 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 19649 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-11T22:01:29.913Z
[junit4] 2> 19660 INFO (zkConnectionManagerCallback-78-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19666 INFO (zkConnectionManagerCallback-79-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19666 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 19668 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 19668 INFO (zkConnectionManagerCallback-81-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19668 INFO (zkConnectionManagerCallback-83-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19674 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 19690 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 19704 INFO (jetty-launcher-72-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35939/solr
[junit4] 2> 19715 INFO (jetty-launcher-72-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35939/solr
[junit4] 2> 19726 INFO (zkConnectionManagerCallback-87-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19732 INFO (jetty-launcher-72-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35939/solr
[junit4] 2> 19738 INFO (zkConnectionManagerCallback-91-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19743 INFO (jetty-launcher-72-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35939/solr
[junit4] 2> 19745 INFO (zkConnectionManagerCallback-96-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19747 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1604798e1b20005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 19748 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1604798e1b20006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 19750 INFO (zkConnectionManagerCallback-99-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19752 INFO
(zkConnectionManagerCallback-101-thread-1-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19752 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1604798e1b20008, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 19752 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1604798e1b20007, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 19757 INFO
(zkConnectionManagerCallback-103-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19764 INFO
(zkConnectionManagerCallback-105-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19765 INFO
(zkConnectionManagerCallback-107-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19785 WARN (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 19785 WARN (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 19785 WARN (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 19797 WARN (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 19797 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 19797 INFO (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 19798 INFO (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 19798 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:35875_solr
[junit4] 2> 19800 INFO (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34903_solr
[junit4] 2> 19800 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.c.Overseer Overseer
(id=99157913749291017-127.0.0.1:35875_solr-n_0000000000) starting
[junit4] 2> 19800 INFO (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40411_solr
[junit4] 2> 19814 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 19814 INFO
(zkCallback-100-thread-1-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 19817 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35875_solr
[junit4] 2> 19824 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 19826 INFO
(zkCallback-104-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 19827 INFO
(zkCallback-100-thread-1-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 19828 INFO (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 19829 INFO (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45619_solr
[junit4] 2> 19832 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 19834 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 19834 INFO
(zkCallback-100-thread-2-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 19848 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 19848 INFO
(zkCallback-104-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 19945 INFO (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34903.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19966 INFO (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34903.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19967 INFO (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34903.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19970 INFO (jetty-launcher-72-thread-2)
[n:127.0.0.1:34903_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node2/.
[junit4] 2> 19978 INFO (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45619.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19989 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35875.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19991 INFO (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40411.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19992 INFO (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45619.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19992 INFO (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45619.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 19993 INFO (jetty-launcher-72-thread-3)
[n:127.0.0.1:45619_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node3/.
[junit4] 2> 20017 WARN (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register
gauge
[junit4] 2> javax.management.InstanceNotFoundException:
solr_40411:dom1=solr,dom2=jvm,name0=buffers,name1=direct,name=MemoryUsed
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565)
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738)
[junit4] 2> at java.util.HashMap.forEach(HashMap.java:1288)
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728)
[junit4] 2> at
org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109)
[junit4] 2> at
org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70)
[junit4] 2> at
org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:881)
[junit4] 2> at
org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:817)
[junit4] 2> at
org.apache.solr.core.CoreContainer.load(CoreContainer.java:549)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183)
[junit4] 2> at
org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1596)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1659)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1316)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1145)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367)
[junit4] 2> at
org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:384)
[junit4] 2> at
org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:247)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 20022 INFO (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40411.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 20022 INFO (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40411.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 20024 INFO (jetty-launcher-72-thread-1)
[n:127.0.0.1:40411_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node1/.
[junit4] 2> 20024 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35875.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 20024 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35875.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 20025 INFO (jetty-launcher-72-thread-4)
[n:127.0.0.1:35875_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node4/.
[junit4] 2> 20171 INFO (zkConnectionManagerCallback-114-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20181 INFO (zkConnectionManagerCallback-118-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20183 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 20183 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35939/solr ready
[junit4] 2> 20206 INFO
(TEST-TestV2Request.testHttpSolrClient-seed#[E94778272556A57A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testHttpSolrClient
[junit4] 2> 20341 INFO
(OverseerThreadFactory-77-thread-1-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.CreateCollectionCmd Create collection test
[junit4] 2> 20454 INFO
(OverseerStateUpdate-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40411/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 20458 INFO
(OverseerStateUpdate-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34903/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 20462 INFO
(OverseerStateUpdate-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"test_shard2_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45619/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 20465 INFO
(OverseerStateUpdate-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"test_shard2_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35875/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 20699 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=test_shard1_replica_n3&action=CREATE&numShards=2&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 20705 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 20717 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=2&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 20718 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 20733 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node8&collection.configName=config&newCollection=true&name=test_shard2_replica_n7&action=CREATE&numShards=2&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 20733 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 20735 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node6&collection.configName=config&newCollection=true&name=test_shard2_replica_n5&action=CREATE&numShards=2&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 20735 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 20844 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20844 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20844 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20844 INFO
(zkCallback-106-thread-2-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20845 INFO
(zkCallback-104-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20846 INFO
(zkCallback-100-thread-2-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20846 INFO
(zkCallback-100-thread-1-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 20846 INFO
(zkCallback-104-thread-2-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 21747 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 21785 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 21790 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 21804 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.IndexSchema
[test_shard1_replica_n3] Schema name=test
[junit4] 2> 21832 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 21846 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.s.IndexSchema
[test_shard2_replica_n5] Schema name=test
[junit4] 2> 21847 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.s.IndexSchema
[test_shard2_replica_n7] Schema name=test
[junit4] 2> 21913 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema
[test_shard1_replica_n1] Schema name=test
[junit4] 2> 22111 WARN (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.IndexSchema Field
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 22111 WARN (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.IndexSchema Field text
is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 22111 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 22111 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.CoreContainer Creating
SolrCore 'test_shard1_replica_n3' using configuration from collection test,
trusted=true
[junit4] 2> 22128 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_34903.solr.core.test.shard1.replica_n3' (registry
'solr.core.test.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 22128 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 22128 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SolrCore
[[test_shard1_replica_n3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node2/test_shard1_replica_n3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node2/./test_shard1_replica_n3/data/]
[junit4] 2> 22152 WARN (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.s.IndexSchema Field
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 22152 WARN (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.s.IndexSchema Field text
is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 22172 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 22172 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.CoreContainer Creating
SolrCore 'test_shard2_replica_n7' using configuration from collection test,
trusted=true
[junit4] 2> 22175 WARN (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.s.IndexSchema Field
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 22175 WARN (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.s.IndexSchema Field text
is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 22175 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 22175 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.CoreContainer Creating
SolrCore 'test_shard2_replica_n5' using configuration from collection test,
trusted=true
[junit4] 2> 22176 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_45619.solr.core.test.shard2.replica_n5' (registry
'solr.core.test.shard2.replica_n5') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 22176 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 22176 WARN (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema Field
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 22176 WARN (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema Field text
is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 22177 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 22177 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'test_shard1_replica_n1' using configuration from collection test,
trusted=true
[junit4] 2> 22177 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_40411.solr.core.test.shard1.replica_n1' (registry
'solr.core.test.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 22178 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 22178 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore
[[test_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node1/test_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node1/./test_shard1_replica_n1/data/]
[junit4] 2> 22202 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_35875.solr.core.test.shard2.replica_n7' (registry
'solr.core.test.shard2.replica_n7') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d6666a2
[junit4] 2> 22202 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 22202 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.SolrCore
[[test_shard2_replica_n7] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node4/test_shard2_replica_n7],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node4/./test_shard2_replica_n7/data/]
[junit4] 2> 22228 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.SolrCore
[[test_shard2_replica_n5] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node3/test_shard2_replica_n5],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001/tempDir-001/node3/./test_shard2_replica_n5/data/]
[junit4] 2> 22306 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 22306 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 22308 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 22308 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 22309 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.SolrIndexSearcher
Opening [Searcher@c5db4ee[test_shard1_replica_n3] main]
[junit4] 2> 22311 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 22311 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 22312 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 22312 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 22312 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 22313 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 22313 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000ms.
[junit4] 2> 22314 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.s.SolrIndexSearcher
Opening [Searcher@523af016[test_shard2_replica_n7] main]
[junit4] 2> 22319 INFO
(searcherExecutor-86-thread-1-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SolrCore
[test_shard1_replica_n3] Registered new searcher
Searcher@c5db4ee[test_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22320 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1586526622930960384
[junit4] 2> 22326 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 22326 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 22327 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000ms.
[junit4] 2> 22341 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 22348 INFO
(searcherExecutor-87-thread-1-processing-n:127.0.0.1:35875_solr
x:test_shard2_replica_n7 s:shard2 c:test r:core_node8) [n:127.0.0.1:35875_solr
c:test s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.SolrCore
[test_shard2_replica_n7] Registered new searcher
Searcher@523af016[test_shard2_replica_n7]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22348 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1586526622960320512
[junit4] 2> 22350 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 22350 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 22351 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 22352 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 22353 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@210ffe14[test_shard1_replica_n1] main]
[junit4] 2> 22355 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 22355 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 22356 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000ms.
[junit4] 2> 22360 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 22360 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 22360 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard2: total=2 found=1 timeoutin=9998ms
[junit4] 2> 22361 INFO
(searcherExecutor-89-thread-1-processing-n:127.0.0.1:40411_solr
x:test_shard1_replica_n1 s:shard1 c:test r:core_node2) [n:127.0.0.1:40411_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore
[test_shard1_replica_n1] Registered new searcher
Searcher@210ffe14[test_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22361 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1586526622973952000
[junit4] 2> 22362 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 22362 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 22363 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.s.SolrIndexSearcher
Opening [Searcher@61427dcc[test_shard2_replica_n5] main]
[junit4] 2> 22366 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 22366 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 22367 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000ms.
[junit4] 2> 22368 INFO
(searcherExecutor-88-thread-1-processing-n:127.0.0.1:45619_solr
x:test_shard2_replica_n5 s:shard2 c:test r:core_node6) [n:127.0.0.1:45619_solr
c:test s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.SolrCore
[test_shard2_replica_n5] Registered new searcher
Searcher@61427dcc[test_shard2_replica_n5]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22375 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1586526622988632064
[junit4] 2> 22461 INFO
(zkCallback-100-thread-2-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22461 INFO
(zkCallback-100-thread-3-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22462 INFO
(zkCallback-104-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22462 INFO
(zkCallback-106-thread-2-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22462 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22462 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22462 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22462 INFO
(zkCallback-104-thread-2-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 22843 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 22843 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 22843 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SyncStrategy Sync
replicas to https://127.0.0.1:34903/solr/test_shard1_replica_n3/
[junit4] 2> 22849 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.PeerSync PeerSync:
core=test_shard1_replica_n3 url=https://127.0.0.1:34903/solr START
replicas=[https://127.0.0.1:40411/solr/test_shard1_replica_n1/] nUpdates=100
[junit4] 2> 22862 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 22862 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 22862 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.SyncStrategy Sync
replicas to https://127.0.0.1:35875/solr/test_shard2_replica_n7/
[junit4] 2> 22862 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.PeerSync PeerSync:
core=test_shard2_replica_n7 url=https://127.0.0.1:35875/solr START
replicas=[https://127.0.0.1:45619/solr/test_shard2_replica_n5/] nUpdates=100
[junit4] 2> 22870 INFO (qtp1934937668-291) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request
[test_shard1_replica_n1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 22879 INFO (qtp373754743-270) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.S.Request
[test_shard2_replica_n5] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 23150 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.PeerSync PeerSync:
core=test_shard1_replica_n3 url=https://127.0.0.1:34903/solr DONE. We have no
versions. sync failed.
[junit4] 2> 23150 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SyncStrategy Leader's
attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 23150 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 23150 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 23156 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:34903/solr/test_shard1_replica_n3/ shard1
[junit4] 2> 23163 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.u.PeerSync PeerSync:
core=test_shard2_replica_n7 url=https://127.0.0.1:35875/solr DONE. We have no
versions. sync failed.
[junit4] 2> 23164 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.SyncStrategy Leader's
attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 23164 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 23164 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 23166 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:35875/solr/test_shard2_replica_n7/ shard2
[junit4] 2> 23268 INFO
(zkCallback-106-thread-2-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-104-thread-2-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-100-thread-3-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-104-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23268 INFO
(zkCallback-100-thread-2-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23307 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 23315 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=test_shard1_replica_n3&action=CREATE&numShards=2&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2616
[junit4] 2> 23317 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 23319 INFO (qtp1418076384-298) [n:127.0.0.1:35875_solr c:test
s:shard2 r:core_node8 x:test_shard2_replica_n7] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=config&newCollection=true&name=test_shard2_replica_n7&action=CREATE&numShards=2&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2586
[junit4] 2> 23384 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=config&newCollection=true&name=test_shard2_replica_n5&action=CREATE&numShards=2&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2649
[junit4] 2> 23389 INFO (qtp1934937668-300) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=2&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2671
[junit4] 2> 23395 INFO (qtp1623923168-294) [n:127.0.0.1:34903_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 23493 INFO
(zkCallback-100-thread-3-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-100-thread-2-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-104-thread-2-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-106-thread-2-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 23493 INFO
(zkCallback-104-thread-1-processing-n:127.0.0.1:45619_solr)
[n:127.0.0.1:45619_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 24343 INFO
(OverseerCollectionConfigSetProcessor-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 24395 INFO (qtp1623923168-294) [n:127.0.0.1:34903_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/collections
params={wt=javabin&version=2} status=0 QTime=4059
[junit4] 2> 24398 INFO (qtp1623923168-297) [n:127.0.0.1:34903_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/c params={wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 24402 ERROR (qtp1623923168-282) [n:127.0.0.1:34903_solr ]
o.a.s.a.V2HttpCall >> path: '/c/_introspect'
[junit4] 2> 24403 INFO (qtp1623923168-282) [n:127.0.0.1:34903_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/c/_introspect
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 24412 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.h.SolrConfigHandler
Executed config commands successfully and persisted to ZK
[{"create-requesthandler":{
[junit4] 2> "name":"/x",
[junit4] 2> "class":"org.apache.solr.handler.DumpRequestHandler",
[junit4] 2> "startup":"lazy"}}]
[junit4] 2> 24413 INFO (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.c.SolrCore config update listener called for core test_shard1_replica_n3
[junit4] 2> 24414 INFO (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.c.SolrCore core reload test_shard1_replica_n3
[junit4] 2> 24414 INFO (qtp1623923168-278) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.h.SolrConfigHandler
Waiting up to 30 secs for 4 replicas to set the property overlay to be of
version 0 for collection test
[junit4] 2> 24414 INFO (Thread-90) [n:127.0.0.1:45619_solr ]
o.a.s.c.SolrCore config update listener called for core test_shard2_replica_n5
[junit4] 2> 24415 INFO (Thread-90) [n:127.0.0.1:45619_solr ]
o.a.s.c.SolrCore core reload test_shard2_replica_n5
[junit4] 2> 24415 INFO
(solrHandlerExecutor-106-thread-1-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24417 INFO
(solrHandlerExecutor-106-thread-4-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24420 INFO
(solrHandlerExecutor-106-thread-3-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24423 INFO (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.c.SolrCore config update listener called for core test_shard1_replica_n1
[junit4] 2> 24424 INFO (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.c.SolrCore config update listener called for core test_shard2_replica_n7
[junit4] 2> 24425 INFO (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.c.SolrCore core reload test_shard2_replica_n7
[junit4] 2> 24426 INFO (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.c.SolrCore core reload test_shard1_replica_n1
[junit4] 2> 24427 INFO (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 24460 INFO
(solrHandlerExecutor-106-thread-2-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24471 INFO (Thread-90) [n:127.0.0.1:45619_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 24477 INFO (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.s.IndexSchema [test_shard1_replica_n3] Schema name=test
[junit4] 2> 24479 INFO (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 24522 INFO (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 24558 INFO (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.s.IndexSchema [test_shard1_replica_n1] Schema name=test
[junit4] 2> 24573 INFO (Thread-90) [n:127.0.0.1:45619_solr ]
o.a.s.s.IndexSchema [test_shard2_replica_n5] Schema name=test
[junit4] 2> 24576 INFO (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.s.IndexSchema [test_shard2_replica_n7] Schema name=test
[junit4] 2> 24666 INFO (qtp373754743-270) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.h.SolrConfigHandler
expecting overlay version 0 but my version is -1
[junit4] 2> 24667 INFO (qtp1623923168-286) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.h.SolrConfigHandler
expecting overlay version 0 but my version is -1
[junit4] 2> 24674 INFO (qtp373754743-270) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.c.S.Request
[test_shard2_replica_n5] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=91
[junit4] 2> 24690 INFO (qtp1623923168-286) [n:127.0.0.1:34903_solr c:test
s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.S.Request
[test_shard1_replica_n3] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=136
[junit4] 2> 24693 INFO
(solrHandlerExecutor-106-thread-4-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
https://127.0.0.1:34903/solr/test_shard1_replica_n3/ for prop overlay after 1
attempts
[junit4] 2> 24693 INFO
(solrHandlerExecutor-106-thread-4-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24693 INFO (qtp1934937668-285) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler
expecting overlay version 0 but my version is -1
[junit4] 2> 24697 INFO (SolrConfigHandler-refreshconf)
[n:127.0.0.1:34903_solr c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 24707 INFO (qtp1934937668-285) [n:127.0.0.1:40411_solr c:test
s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request
[test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=52
[junit4] 2> 24707 INFO (SolrConfigHandler-refreshconf)
[n:127.0.0.1:40411_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 24725 INFO (SolrConfigHandler-refreshconf)
[n:127.0.0.1:45619_solr c:test s:shard2 r:core_node6 x:test_shard2_replica_n5]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 24732 INFO
(solrHandlerExecutor-106-thread-1-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
https://127.0.0.1:45619/solr/test_shard2_replica_n5/ for prop overlay after 1
attempts
[junit4] 2> 24732 INFO
(solrHandlerExecutor-106-thread-1-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24755 INFO
(solrHandlerExecutor-106-thread-3-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
https://127.0.0.1:40411/solr/test_shard1_replica_n1/ for prop overlay after 1
attempts
[junit4] 2> 24755 INFO
(solrHandlerExecutor-106-thread-3-processing-n:127.0.0.1:34903_solr
x:test_shard1_replica_n3 s:shard1 c:test r:core_node4) [n:127.0.0.1:34903_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 24860 WARN (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 24860 WARN (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 24861 INFO (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 24861 INFO (Thread-87) [n:127.0.0.1:34903_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'test_shard1_replica_n3' using
configuration from collection test
[junit4] 2> 24871 WARN (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 24871 WARN (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 24872 INFO (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 24872 INFO (Thread-88) [n:127.0.0.1:40411_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'test_shard1_replica_n1' using
configuration from collection test
[junit4] 2> 24904 INFO (qtp373754743-277) [n:127.0.0.1:45619_solr c:test
s:shard2 r:core_node6 x:test_shard2_replica_n5] o.a.s.h.SolrConfigHandler
expecting overlay version 0 but my version is -1
[junit4] 2> 24904 WARN (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 24904 WARN (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 24906 INFO (Thread-89) [n:127.0.0.1:35875_solr ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2
[...truncated too long message...]
.solr.metrics.reporters.SolrJmxReporter@35daa172: rootName = solr_34903, domain
= solr.jetty, service url = null, agent id = null] for registry solr.jetty /
com.codahale.metrics.MetricRegistry@638aa3eb
[junit4] 2> 85662 INFO (jetty-closer-73-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 85662 INFO (jetty-closer-73-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@40c944c0: rootName =
solr_35875, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@297d9bd6
[junit4] 2> 85668 INFO (jetty-closer-73-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 85668 INFO (jetty-closer-73-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4bc383ea: rootName =
solr_35875, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@638aa3eb
[junit4] 2> 85669 INFO (jetty-closer-73-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 85669 INFO (jetty-closer-73-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@33f47b: rootName =
solr_40411, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@297d9bd6
[junit4] 2> 85670 INFO (jetty-closer-73-thread-4) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:35875_solr
[junit4] 2> 85671 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 85672 INFO
(zkCallback-100-thread-3-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 85672 INFO (jetty-closer-73-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 85679 INFO (jetty-closer-73-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 85679 INFO (jetty-closer-73-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@571a59d2: rootName =
solr_40411, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@638aa3eb
[junit4] 2> 85681 INFO (jetty-closer-73-thread-4) [ ] o.a.s.c.Overseer
Overseer (id=99157913749291017-127.0.0.1:35875_solr-n_0000000000) closing
[junit4] 2> 85681 INFO
(OverseerStateUpdate-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:35875_solr
[junit4] 2> 85684 INFO (jetty-closer-73-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:40411_solr
[junit4] 2> 85685 INFO
(zkCallback-100-thread-3-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 85685 INFO
(zkCallback-106-thread-1-processing-n:127.0.0.1:40411_solr)
[n:127.0.0.1:40411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 85686 INFO (jetty-closer-73-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 85694 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 85694 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 85698 WARN
(OverseerAutoScalingTriggerThread-99157913749291017-127.0.0.1:35875_solr-n_0000000000)
[n:127.0.0.1:35875_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 85698 INFO (jetty-closer-73-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:34903_solr
[junit4] 2> 85702 INFO
(zkCallback-100-thread-3-processing-n:127.0.0.1:35875_solr)
[n:127.0.0.1:35875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 85702 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 85702 INFO (jetty-closer-73-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 85704 INFO (jetty-closer-73-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@45bf8212{/solr,null,UNAVAILABLE}
[junit4] 2> 85705 INFO
(zkCallback-102-thread-2-processing-n:127.0.0.1:34903_solr)
[n:127.0.0.1:34903_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:34903_solr
[junit4] 2> 85705 INFO (jetty-closer-73-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@53718fb2{/solr,null,UNAVAILABLE}
[junit4] 2> 85713 INFO (jetty-closer-73-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@193d5104{/solr,null,UNAVAILABLE}
[junit4] 2> 85726 ERROR
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 85726 INFO
(SUITE-TestV2Request-seed#[E94778272556A57A]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35939 35939
[junit4] 2> 85866 INFO (Thread-50) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:35939 35939
[junit4] 2> 85867 WARN (Thread-50) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/configs/config
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/collections/test/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.request.TestV2Request_E94778272556A57A-001
[junit4] 2> Dec 11, 2017 10:02:36 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> Dec 11, 2017 10:02:56 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.client.solrj.request.TestV2Request:
[junit4] 2> 1) Thread[id=376, name=Connection evictor,
state=TIMED_WAITING, group=TGRP-TestV2Request]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Dec 11, 2017 10:02:56 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=376, name=Connection evictor,
state=TIMED_WAITING, group=TGRP-TestV2Request]
[junit4] 2> Dec 11, 2017 10:02:56 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1671, maxMBSortInHeap=7.290649114863944,
sim=RandomSimilarity(queryNorm=false): {}, locale=es-CU,
timezone=Pacific/Tongatapu
[junit4] 2> NOTE: Linux 4.10.0-40-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=329825768,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [CorrelationEvaluatorTest,
TestDelegationTokenResponse, JDBCStreamTest, ArcTangentEvaluatorTest,
TestDelegationTokenRequest, BasicHttpSolrClientTest, TestV2Request]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestV2Request
-Dtests.seed=E94778272556A57A -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=es-CU -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestV2Request (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.client.solrj.request.TestV2Request:
[junit4] > 1) Thread[id=376, name=Connection evictor,
state=TIMED_WAITING, group=TGRP-TestV2Request]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([E94778272556A57A]:0)
[junit4] Completed [114/146 (1!)] on J1 in 86.53s, 3 tests, 3 errors <<<
FAILURES!
[...truncated 46529 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
