Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2696/
Java: 64bit/jdk-11-ea+28 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED:
org.apache.solr.cloud.rule.RulesTest.testPortRuleInPresenceOfClusterPolicy
Error Message:
Could not find collection : portRuleColl2
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : portRuleColl2
at
__randomizedtesting.SeedInfo.seed([7F5C8BB89524CFC8:C674073822FD8580]:0)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
at
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:258)
at
org.apache.solr.cloud.rule.RulesTest.testPortRuleInPresenceOfClusterPolicy(RulesTest.java:119)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED:
org.apache.solr.cloud.rule.RulesTest.testPortRuleInPresenceOfClusterPolicy
Error Message:
Could not find collection : portRuleColl2
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : portRuleColl2
at
__randomizedtesting.SeedInfo.seed([7F5C8BB89524CFC8:C674073822FD8580]:0)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
at
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:258)
at
org.apache.solr.cloud.rule.RulesTest.testPortRuleInPresenceOfClusterPolicy(RulesTest.java:119)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 12594 lines...]
[junit4] Suite: org.apache.solr.cloud.rule.RulesTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/init-core-data-001
[junit4] 2> 48584 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 48587 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 48588 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 48591 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001
[junit4] 2> 48591 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 48605 INFO (Thread-64) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 48605 INFO (Thread-64) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 48643 ERROR (Thread-64) [ ] 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> 48704 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.c.ZkTestServer start zk server on port:39143
[junit4] 2> 48710 INFO (zkConnectionManagerCallback-120-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48768 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 11+28
[junit4] 2> 48768 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 11+28
[junit4] 2> 48769 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 11+28
[junit4] 2> 48771 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 11+28
[junit4] 2> 48771 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 11+28
[junit4] 2> 48854 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48854 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48854 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 48859 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@36fbc4c8{/solr,null,AVAILABLE}
[junit4] 2> 48866 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48866 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48866 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 48867 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48867 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48867 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 48867 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@25f36bfa{/solr,null,AVAILABLE}
[junit4] 2> 48875 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@480f18d{/solr,null,AVAILABLE}
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@c5e5fb1{SSL,[ssl,
http/1.1]}{127.0.0.1:38483}
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.e.j.s.Server Started @48935ms
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38483}
[junit4] 2> 48899 ERROR (jetty-launcher-117-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.5.0
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48899 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 48900 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-06T08:36:14.312988Z
[junit4] 2> 48902 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48902 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48902 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 48903 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@25f0f82{/solr,null,AVAILABLE}
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@4bb8475c{SSL,[ssl,
http/1.1]}{127.0.0.1:40913}
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@195191ec{SSL,[ssl,
http/1.1]}{127.0.0.1:38025}
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.e.j.s.Server Started @48940ms
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.e.j.s.Server Started @48940ms
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40913}
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38025}
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@34b15c3d{SSL,[ssl,
http/1.1]}{127.0.0.1:44887}
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.e.j.s.Server Started @48940ms
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44887}
[junit4] 2> 48904 ERROR (jetty-launcher-117-thread-5) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 48904 ERROR (jetty-launcher-117-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.5.0
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.5.0
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48904 ERROR (jetty-launcher-117-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.5.0
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-06T08:36:14.317875Z
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-06T08:36:14.317875Z
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 48904 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-06T08:36:14.317926Z
[junit4] 2> 48905 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@27f2f054{/solr,null,AVAILABLE}
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@78e7da9b{SSL,[ssl,
http/1.1]}{127.0.0.1:41185}
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.e.j.s.Server Started @48942ms
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41185}
[junit4] 2> 48906 ERROR (jetty-launcher-117-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.5.0
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 48906 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-06T08:36:14.319549Z
[junit4] 2> 48956 INFO (zkConnectionManagerCallback-127-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48956 INFO (zkConnectionManagerCallback-126-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48956 INFO (zkConnectionManagerCallback-128-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48967 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 48967 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 48967 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 48967 INFO (zkConnectionManagerCallback-122-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48973 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 49002 INFO (zkConnectionManagerCallback-130-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49010 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 49150 INFO (jetty-launcher-117-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39143/solr
[junit4] 2> 49187 INFO (zkConnectionManagerCallback-134-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49242 INFO (zkConnectionManagerCallback-136-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49311 INFO (jetty-launcher-117-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39143/solr
[junit4] 2> 49337 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:40913_solr
[junit4] 2> 49338 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.c.Overseer Overseer
(id=72176781192462343-127.0.0.1:40913_solr-n_0000000000) starting
[junit4] 2> 49376 INFO (zkConnectionManagerCallback-142-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49382 INFO (jetty-launcher-117-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39143/solr
[junit4] 2> 49387 INFO (jetty-launcher-117-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39143/solr
[junit4] 2> 49423 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1006c666c020008, likely client has closed socket
[junit4] 2> 49446 INFO (zkConnectionManagerCallback-155-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49447 INFO (zkConnectionManagerCallback-148-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49450 INFO (zkConnectionManagerCallback-152-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49463 INFO (zkConnectionManagerCallback-157-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49487 INFO (jetty-launcher-117-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39143/solr
[junit4] 2> 49488 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39143/solr ready
[junit4] 2> 49491 INFO
(OverseerStateUpdate-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:40913_solr
[junit4] 2> 49504 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40913_solr
[junit4] 2> 49504 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38025_solr
[junit4] 2> 49505 INFO (zkConnectionManagerCallback-163-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49506 INFO (zkConnectionManagerCallback-160-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49530 INFO (zkCallback-135-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 49570 INFO (zkCallback-147-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 49570 INFO (zkCallback-156-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 49576 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 49579 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 49579 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44887_solr
[junit4] 2> 49583 INFO (zkCallback-147-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 49583 INFO (zkCallback-135-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 49583 INFO (zkCallback-156-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 49595 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 49634 INFO (zkCallback-159-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 49658 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40913.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49670 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40913.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49670 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40913.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49671 INFO (jetty-launcher-117-thread-4)
[n:127.0.0.1:40913_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node4/.
[junit4] 2> 49682 INFO (zkConnectionManagerCallback-165-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49703 INFO (zkConnectionManagerCallback-175-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49715 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 49715 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 49718 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 49718 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38483_solr
[junit4] 2> 49719 INFO (zkCallback-135-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 49719 INFO (zkCallback-159-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 49720 INFO (zkCallback-147-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 49720 INFO (zkCallback-156-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 49733 INFO (zkConnectionManagerCallback-173-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49733 INFO (zkConnectionManagerCallback-182-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49756 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 49756 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 49759 INFO (zkCallback-164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 49759 INFO (zkCallback-174-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 49762 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39143/solr ready
[junit4] 2> 49763 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39143/solr ready
[junit4] 2> 49765 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 49765 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41185_solr
[junit4] 2> 49771 INFO (zkCallback-147-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49771 INFO (zkCallback-135-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49771 INFO (zkCallback-156-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49771 INFO (zkCallback-164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49771 INFO (zkCallback-159-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49771 INFO (zkCallback-174-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49822 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 49838 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 49858 INFO (zkCallback-181-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49858 INFO (zkCallback-172-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 49895 INFO (zkConnectionManagerCallback-191-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49903 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 49905 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39143/solr ready
[junit4] 2> 49909 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38025.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49914 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 49919 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38025.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49920 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38025.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49921 INFO (jetty-launcher-117-thread-5)
[n:127.0.0.1:38025_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node5/.
[junit4] 2> 49924 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44887.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49932 INFO (zkConnectionManagerCallback-198-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49933 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38483.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49939 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 49940 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39143/solr ready
[junit4] 2> 49940 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44887.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49941 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44887.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49942 INFO (jetty-launcher-117-thread-2)
[n:127.0.0.1:44887_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node2/.
[junit4] 2> 49945 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38483.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49947 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38483.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49947 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 49949 INFO (jetty-launcher-117-thread-3)
[n:127.0.0.1:38483_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node3/.
[junit4] 2> 49961 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41185.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49969 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41185.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49970 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41185.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 49971 INFO (jetty-launcher-117-thread-1)
[n:127.0.0.1:41185_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node1/.
[junit4] 2> 50167 INFO (zkConnectionManagerCallback-202-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 50171 INFO (zkConnectionManagerCallback-207-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 50172 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 50173 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker) [
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39143/solr
ready
[junit4] 2> 50201 INFO
(TEST-RulesTest.testPortRuleInPresenceOfClusterPolicy-seed#[7F5C8BB89524CFC8])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testPortRuleInPresenceOfClusterPolicy
[junit4] 2> 50392 INFO (qtp605396056-385) [n:127.0.0.1:38025_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 50431 INFO (qtp1321711668-377) [n:127.0.0.1:38483_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 50473 INFO (qtp429795579-391) [n:127.0.0.1:40913_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 50514 INFO (qtp1428608026-375) [n:127.0.0.1:41185_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 50550 INFO (qtp495460572-381) [n:127.0.0.1:44887_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 50555 INFO (qtp605396056-398) [n:127.0.0.1:38025_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=231
[junit4] 2> 50559 INFO (qtp605396056-376) [n:127.0.0.1:38025_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
shards=shard1&collection.configName=conf&snitch=class:ImplicitSnitch&name=portRuleColl2&router.name=implicit&nrtReplicas=2&action=CREATE&rule=port:40913&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 50564 INFO
(OverseerThreadFactory-150-thread-1-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
portRuleColl2
[junit4] 2> 50689 INFO
(OverseerStateUpdate-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"portRuleColl2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"portRuleColl2_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40913/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 50691 INFO
(OverseerStateUpdate-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"portRuleColl2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"portRuleColl2_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40913/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 50900 WARN (qtp429795579-380) [ ]
o.e.j.u.t.s.EatWhatYouKill
[junit4] 2> java.util.NoSuchElementException: No value present
[junit4] 2> at java.util.Optional.get(Optional.java:148) ~[?:?]
[junit4] 2> at
sun.security.ssl.ServerHello$T13ServerHelloProducer.produce(ServerHello.java:551)
~[?:?]
[junit4] 2> at
sun.security.ssl.SSLHandshake.produce(SSLHandshake.java:436) ~[?:?]
[junit4] 2> at
sun.security.ssl.ClientHello$T13ClientHelloConsumer.goServerHello(ClientHello.java:1189)
~[?:?]
[junit4] 2> at
sun.security.ssl.ClientHello$T13ClientHelloConsumer.consume(ClientHello.java:1125)
~[?:?]
[junit4] 2> at
sun.security.ssl.ClientHello$ClientHelloConsumer.onClientHello(ClientHello.java:831)
~[?:?]
[junit4] 2> at
sun.security.ssl.ClientHello$ClientHelloConsumer.consume(ClientHello.java:792)
~[?:?]
[junit4] 2> at
sun.security.ssl.SSLHandshake.consume(SSLHandshake.java:392) ~[?:?]
[junit4] 2> at
sun.security.ssl.HandshakeContext.dispatch(HandshakeContext.java:444) ~[?:?]
[junit4] 2> at
sun.security.ssl.SSLEngineImpl$DelegatedTask$DelegatedAction.run(SSLEngineImpl.java:1065)
~[?:?]
[junit4] 2> at
sun.security.ssl.SSLEngineImpl$DelegatedTask$DelegatedAction.run(SSLEngineImpl.java:1052)
~[?:?]
[junit4] 2> at java.security.AccessController.doPrivileged(Native
Method) ~[?:?]
[junit4] 2> at
sun.security.ssl.SSLEngineImpl$DelegatedTask.run(SSLEngineImpl.java:999) ~[?:?]
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.fill(SslConnection.java:775)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.fillRequestBuffer(HttpConnection.java:331)
~[jetty-server-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:243)
~[jetty-server-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
~[jetty-io-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132)
~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 50901 ERROR
(OverseerThreadFactory-150-thread-1-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error
from shard: https://127.0.0.1:40913/solr
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: IOException
occured when talking to server at: https://127.0.0.1:40913/solr
[junit4] 2> at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) ~[java/:?]
[junit4] 2> at
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:172)
~[java/:?]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
~[metrics-core-3.2.6.jar:3.2.6]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
~[?:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> Caused by: java.net.SocketException: Socket input is already
shutdown
[junit4] 2> at
sun.security.ssl.SSLSocketImpl.getInputStream(SSLSocketImpl.java:696) ~[?:?]
[junit4] 2> at
org.apache.http.impl.BHttpConnectionBase.getSocketInputStream(BHttpConnectionBase.java:142)
~[httpcore-4.4.6.jar:4.4.6]
[junit4] 2> at
org.apache.http.impl.conn.LoggingManagedHttpClientConnection.getSocketInputStream(LoggingManagedHttpClientConnection.java:103)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.BHttpConnectionBase.ensureOpen(BHttpConnectionBase.java:134)
~[httpcore-4.4.6.jar:4.4.6]
[junit4] 2> at
org.apache.http.impl.DefaultBHttpClientConnection.sendRequestHeader(DefaultBHttpClientConnection.java:140)
~[httpcore-4.4.6.jar:4.4.6]
[junit4] 2> at
org.apache.http.impl.conn.CPoolProxy.sendRequestHeader(CPoolProxy.java:155)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:205)
~[httpcore-4.4.6.jar:4.4.6]
[junit4] 2> at
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
~[httpcore-4.4.6.jar:4.4.6]
[junit4] 2> at
org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:120)
~[java/:?]
[junit4] 2> at
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
~[httpclient-4.5.3.jar:4.5.3]
[junit4] 2> at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
~[java/:?]
[junit4] 2> ... 12 more
[junit4] 2> 50914 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=portRuleColl2_shard1_replica_n1&action=CREATE&numShards=1&collection=portRuleColl2&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 50914 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 2147483647 transient cores
[junit4] 2> 51973 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 51981 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.s.IndexSchema [portRuleColl2_shard1_replica_n1] Schema name=minimal
[junit4] 2> 51984 WARN (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 51986 WARN (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.TrieLongField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 51987 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 51988 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'portRuleColl2_shard1_replica_n1' using
configuration from collection portRuleColl2, trusted=true
[junit4] 2> 51995 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40913.solr.core.portRuleColl2.shard1.replica_n1' (registry
'solr.core.portRuleColl2.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 51995 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SolrCore [[portRuleColl2_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node4/portRuleColl2_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node4/./portRuleColl2_shard1_replica_n1/data/]
[junit4] 2> 52111 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 52111 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 52112 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 52112 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 52114 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7cbb0c42[portRuleColl2_shard1_replica_n1] main]
[junit4] 2> 52127 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 52127 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 52128 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 52128 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1610846544898031616
[junit4] 2> 52135 INFO
(searcherExecutor-171-thread-1-processing-n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1 c:portRuleColl2 s:shard1 r:core_node3)
[n:127.0.0.1:40913_solr c:portRuleColl2 s:shard1 r:core_node3
x:portRuleColl2_shard1_replica_n1] o.a.s.c.SolrCore
[portRuleColl2_shard1_replica_n1] Registered new searcher
Searcher@7cbb0c42[portRuleColl2_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 52136 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/portRuleColl2/terms/shard1 to Terms{values={core_node3=0},
version=0}
[junit4] 2> 52139 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9998ms
[junit4] 2> 62166 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Was waiting for replicas to come up, but
they are taking too long - assuming they won't come back till later
[junit4] 2> 62166 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 62166 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:40913/solr/portRuleColl2_shard1_replica_n1/
[junit4] 2> 62166 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 62166 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.SyncStrategy
https://127.0.0.1:40913/solr/portRuleColl2_shard1_replica_n1/ has no replicas
[junit4] 2> 62169 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:40913/solr/portRuleColl2_shard1_replica_n1/ shard1
[junit4] 2> 62220 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 62322 INFO (zkCallback-135-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/portRuleColl2/state.json] for collection [portRuleColl2] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 62353 INFO (qtp429795579-393) [n:127.0.0.1:40913_solr
c:portRuleColl2 s:shard1 r:core_node3 x:portRuleColl2_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=portRuleColl2_shard1_replica_n1&action=CREATE&numShards=1&collection=portRuleColl2&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=11439
[junit4] 2> 62354 ERROR
(OverseerThreadFactory-150-thread-1-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Cleaning up collection [portRuleColl2].
[junit4] 2> 62357 INFO
(OverseerThreadFactory-150-thread-1-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 62359 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.portRuleColl2.shard1.replica_n1, tag=null
[junit4] 2> 62359 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5badd031: rootName =
solr_40913, domain = solr.core.portRuleColl2.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.portRuleColl2.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@49a7b881
[junit4] 2> 62373 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.c.SolrCore
[portRuleColl2_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@5fe0d4c3
[junit4] 2> 62373 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.portRuleColl2.shard1.replica_n1, tag=5fe0d4c3
[junit4] 2> 62373 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.portRuleColl2.shard1.leader, tag=5fe0d4c3
[junit4] 2> 62378 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/portRuleColl2/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 62380 INFO (qtp429795579-552) [n:127.0.0.1:40913_solr
x:portRuleColl2_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={deleteInstanceDir=true&deleteMetricsHistory=true&core=portRuleColl2_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=21
[junit4] 2> 62484 INFO (zkCallback-135-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDeleted
path:/collections/portRuleColl2/state.json] for collection [portRuleColl2] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 63088 INFO
(OverseerThreadFactory-150-thread-1-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.CreateCollectionCmd Cleaned up
artifacts for failed create collection for [portRuleColl2]
[junit4] 2> 63091 INFO (qtp605396056-376) [n:127.0.0.1:38025_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> 64575 INFO
(OverseerCollectionConfigSetProcessor-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_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> 93094 ERROR (qtp605396056-376) [n:127.0.0.1:38025_solr ]
o.a.s.h.a.CollectionsHandler Timed out waiting for new collection's replicas to
become ACTIVE with timeout=30
[junit4] 2> 93094 INFO (qtp605396056-376) [n:127.0.0.1:38025_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={shards=shard1&collection.configName=conf&snitch=class:ImplicitSnitch&name=portRuleColl2&router.name=implicit&nrtReplicas=2&action=CREATE&rule=port:40913&wt=javabin&version=2}
status=0 QTime=42534
[junit4] 2> 93096 INFO
(TEST-RulesTest.testPortRuleInPresenceOfClusterPolicy-seed#[7F5C8BB89524CFC8])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 93098 INFO
(TEST-RulesTest.testPortRuleInPresenceOfClusterPolicy-seed#[7F5C8BB89524CFC8])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testPortRuleInPresenceOfClusterPolicy
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RulesTest
-Dtests.method=testPortRuleInPresenceOfClusterPolicy
-Dtests.seed=7F5C8BB89524CFC8 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=sw -Dtests.timezone=Libya -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 42.9s J0 | RulesTest.testPortRuleInPresenceOfClusterPolicy
<<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not
find collection : portRuleColl2
[junit4] > at
__randomizedtesting.SeedInfo.seed([7F5C8BB89524CFC8:C674073822FD8580]:0)
[junit4] > at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
[junit4] > at
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:258)
[junit4] > at
org.apache.solr.cloud.rule.RulesTest.testPortRuleInPresenceOfClusterPolicy(RulesTest.java:119)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> 93124 INFO
(TEST-RulesTest.testHostFragmentRuleThrowsExceptionWhenIpDoesNotMatch-seed#[7F5C8BB89524CFC8])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testHostFragmentRuleThrowsExceptionWhenIpDoesNotMatch
[junit4] 2> 93127 INFO (qtp605396056-395) [n:127.0.0.1:38025_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
shards=shard1&collection.configName=conf&snitch=class:ImplicitSnitch&name=ipRuleColl&router.name=implicit&nrtReplicas=2&action=CREATE&rule=ip_2:0&rule=ip_1:19999&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 93129 INFO
(OverseerThreadFactory-150-thread-2-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
ipRuleColl
[junit4] 2> 93233 ERROR
(OverseerThreadFactory-150-thread-2-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Collection: ipRuleColl operation: create
failed:org.apache.solr.common.SolrException: Could not identify nodes matching
the rules [{"ip_2":"0"}, {"ip_1":"19999"}]
[junit4] 2> tag values{
[junit4] 2> "127.0.0.1:38025_solr":{
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_2":"0"},
[junit4] 2> "127.0.0.1:44887_solr":{
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_2":"0"},
[junit4] 2> "127.0.0.1:38483_solr":{
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_2":"0"},
[junit4] 2> "127.0.0.1:40913_solr":{
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_2":"0"},
[junit4] 2> "127.0.0.1:41185_solr":{
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_2":"0"}}
[junit4] 2> at
org.apache.solr.cloud.rule.ReplicaAssigner.getNodeMappings(ReplicaAssigner.java:126)
[junit4] 2> at
org.apache.solr.cloud.api.collections.Assign.identifyNodes(Assign.java:294)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateCollectionCmd.buildReplicaPositions(CreateCollectionCmd.java:390)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:182)
[junit4] 2> at
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:259)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:478)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 93246 INFO (qtp605396056-395) [n:127.0.0.1:38025_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={shards=shard1&collection.configName=conf&snitch=class:ImplicitSnitch&name=ipRuleColl&router.name=implicit&nrtReplicas=2&action=CREATE&rule=ip_2:0&rule=ip_1:19999&wt=javabin&version=2}
status=400 QTime=118
[junit4] 2> 93248 INFO
(TEST-RulesTest.testHostFragmentRuleThrowsExceptionWhenIpDoesNotMatch-seed#[7F5C8BB89524CFC8])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 93250 INFO (qtp605396056-538) [n:127.0.0.1:38025_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=ipRuleColl&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 93259 INFO
(OverseerCollectionConfigSetProcessor-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 93260 INFO
(OverseerThreadFactory-150-thread-3-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 93965 INFO (qtp605396056-538) [n:127.0.0.1:38025_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=ipRuleColl&action=DELETE&wt=javabin&version=2} status=0 QTime=714
[junit4] 2> 93966 INFO
(TEST-RulesTest.testHostFragmentRuleThrowsExceptionWhenIpDoesNotMatch-seed#[7F5C8BB89524CFC8])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testHostFragmentRuleThrowsExceptionWhenIpDoesNotMatch
[junit4] 2> 94001 INFO
(TEST-RulesTest.testHostFragmentRule-seed#[7F5C8BB89524CFC8]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testHostFragmentRule
[junit4] 2> 94006 INFO (qtp605396056-395) [n:127.0.0.1:38025_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
shards=shard1&collection.configName=conf&snitch=class:ImplicitSnitch&name=hostFragment&router.name=implicit&nrtReplicas=2&action=CREATE&rule=ip_2:0&rule=ip_1:1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 94009 INFO
(OverseerThreadFactory-150-thread-4-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
hostFragment
[junit4] 2> 94009 INFO
(OverseerCollectionConfigSetProcessor-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 94226 INFO
(OverseerStateUpdate-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"hostFragment",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"hostFragment_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:44887/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 94228 INFO
(OverseerStateUpdate-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"hostFragment",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"hostFragment_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41185/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 94457 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=hostFragment_shard1_replica_n1&action=CREATE&numShards=1&collection=hostFragment&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 94470 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
x:hostFragment_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=hostFragment_shard1_replica_n2&action=CREATE&numShards=1&collection=hostFragment&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 95471 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 95478 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.s.IndexSchema [hostFragment_shard1_replica_n1] Schema name=minimal
[junit4] 2> 95481 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 95481 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'hostFragment_shard1_replica_n1' using
configuration from collection hostFragment, trusted=true
[junit4] 2> 95481 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44887.solr.core.hostFragment.shard1.replica_n1' (registry
'solr.core.hostFragment.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 95482 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.SolrCore [[hostFragment_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node2/hostFragment_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node2/./hostFragment_shard1_replica_n1/data/]
[junit4] 2> 95515 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 95524 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.s.IndexSchema [hostFragment_shard1_replica_n2] Schema name=minimal
[junit4] 2> 95526 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 95526 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'hostFragment_shard1_replica_n2' using
configuration from collection hostFragment, trusted=true
[junit4] 2> 95529 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41185.solr.core.hostFragment.shard1.replica_n2' (registry
'solr.core.hostFragment.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d084fd3
[junit4] 2> 95529 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.c.SolrCore [[hostFragment_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node1/hostFragment_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001/tempDir-001/node1/./hostFragment_shard1_replica_n2/data/]
[junit4] 2> 95598 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 95599 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 95600 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 95600 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 95601 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@663f2a1d[hostFragment_shard1_replica_n1] main]
[junit4] 2> 95602 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 95603 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 95603 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 95603 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1610846590484873216
[junit4] 2> 95609 INFO
(searcherExecutor-176-thread-1-processing-n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1 c:hostFragment s:shard1 r:core_node3)
[n:127.0.0.1:44887_solr c:hostFragment s:shard1 r:core_node3
x:hostFragment_shard1_replica_n1] o.a.s.c.SolrCore
[hostFragment_shard1_replica_n1] Registered new searcher
Searcher@663f2a1d[hostFragment_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 95616 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/hostFragment/terms/shard1 to Terms{values={core_node3=0},
version=0}
[junit4] 2> 95624 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 95640 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 95640 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 95642 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 95642 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 95646 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@59002701[hostFragment_shard1_replica_n2] main]
[junit4] 2> 95647 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 95648 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 95648 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 95649 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1610846590533107712
[junit4] 2> 95650 INFO
(searcherExecutor-177-thread-1-processing-n:127.0.0.1:41185_solr
x:hostFragment_shard1_replica_n2 c:hostFragment s:shard1 r:core_node4)
[n:127.0.0.1:41185_solr c:hostFragment s:shard1 r:core_node4
x:hostFragment_shard1_replica_n2] o.a.s.c.SolrCore
[hostFragment_shard1_replica_n2] Registered new searcher
Searcher@59002701[hostFragment_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 95659 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/hostFragment/terms/shard1 to Terms{values={core_node3=0,
core_node4=0}, version=1}
[junit4] 2> 96125 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 96125 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 96125 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:44887/solr/hostFragment_shard1_replica_n1/
[junit4] 2> 96126 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=hostFragment_shard1_replica_n1
url=https://127.0.0.1:44887/solr START
replicas=[https://127.0.0.1:41185/solr/hostFragment_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 96126 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=hostFragment_shard1_replica_n1
url=https://127.0.0.1:44887/solr DONE. We have no versions. sync failed.
[junit4] 2> 96137 INFO (qtp1428608026-415) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.c.S.Request [hostFragment_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 96138 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 96138 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
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> 96138 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 96142 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:44887/solr/hostFragment_shard1_replica_n1/ shard1
[junit4] 2> 96244 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 96346 INFO (zkCallback-159-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/hostFragment/state.json] for collection [hostFragment] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 96521 INFO (qtp495460572-544) [n:127.0.0.1:44887_solr
c:hostFragment s:shard1 r:core_node3 x:hostFragment_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=hostFragment_shard1_replica_n1&action=CREATE&numShards=1&collection=hostFragment&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2064
[junit4] 2> 96663 INFO (qtp1428608026-408) [n:127.0.0.1:41185_solr
c:hostFragment s:shard1 r:core_node4 x:hostFragment_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=hostFragment_shard1_replica_n2&action=CREATE&numShards=1&collection=hostFragment&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2193
[junit4] 2> 96669 INFO (qtp605396056-395) [n:127.0.0.1:38025_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> 96766 INFO (zkCallback-159-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/hostFragment/state.json] for collection [hostFragment] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 96766 INFO (zkCallback-174-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/hostFragment/state.json] for collection [hostFragment] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 97670 INFO (qtp605396056-395) [n:127.0.0.1:38025_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={shards=shard1&collection.configName=conf&snitch=class:ImplicitSnitch&name=hostFragment&router.name=implicit&nrtReplicas=2&action=CREATE&rule=ip_2:0&rule=ip_1:1&wt=javabin&version=2}
status=0 QTime=3663
[junit4] 2> 97671 INFO
(TEST-RulesTest.testHostFragmentRule-seed#[7F5C8BB89524CFC8]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 97673 INFO (qtp605396056-538) [n:127.0.0.1:38025_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=hostFragment&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 97675 INFO
(OverseerCollectionConfigSetProcessor-72176781192462343-127.0.0.1:40913_solr-n_0000000000)
[n:127.0.0.1:40913_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 97675 INFO
(OverseerThreadFactory-150-thread-5-processing-n:127.0.0.1:40913_solr)
[n:127.0.0.1:40913_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 97678 INFO (qtp1428608026-375) [n:127.0.0.1:41185_solr
x:hostFragment_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.hostFragment.shard1.replica_n2, tag=null
[junit4] 2> 97678 INFO (qtp495460572-406) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.hostFragment.shard1.replica_n1, tag=null
[junit4] 2> 97678 INFO (qtp495460572-406) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@59cdad5: rootName =
solr_44887, domain = solr.core.hostFragment.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.hostFragment.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@422c8acb
[junit4] 2> 97678 INFO (qtp1428608026-375) [n:127.0.0.1:41185_solr
x:hostFragment_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@21ab352a: rootName =
solr_41185, domain = solr.core.hostFragment.shard1.replica_n2, service url =
null, agent id = null] for registry solr.core.hostFragment.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@1ec8abfe
[junit4] 2> 97687 INFO (qtp495460572-406) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.c.SolrCore
[hostFragment_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1f7cb932
[junit4] 2> 97687 INFO (qtp495460572-406) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.hostFragment.shard1.replica_n1, tag=1f7cb932
[junit4] 2> 97687 INFO (qtp495460572-406) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.hostFragment.shard1.leader, tag=1f7cb932
[junit4] 2> 97692 INFO (qtp495460572-406) [n:127.0.0.1:44887_solr
x:hostFragment_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/hostFragment/terms/shard1 to Terms{values={core_node4=0},
version=2}
[junit4] 2> 97693 WARN (zkCallback-174-thread-2) [ ]
o.a.s.c.LeaderElector
[junit4] 2> org.apache.solr.common.SolrException: SolrCore not
found:hostFragment_shard1_replica_n2 in []
[junit4] 2> at org.apache.solr.clo
[...truncated too long message...]
er.java:180)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:734)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:715)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:531)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 111703 INFO (qtp827199664-334) [n:127.0.0.1:37309_solr
x:rulesColl_shard2_replica_n5] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={nodeName=127.0.0.1:43711_solr&onlyIfLeaderActive=true&core=rulesColl_shard2_replica_n5&coreNodeName=core_node8&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=400 QTime=10002
[junit4] 2> 113062 WARN (zkCallback-188-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 113063 INFO (jetty-closer-108-thread-5) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@75754277{/solr,null,UNAVAILABLE}
[junit4] 2> 113063 INFO (jetty-closer-108-thread-5) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 113067 INFO (jetty-closer-108-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@74aa0427{/solr,null,UNAVAILABLE}
[junit4] 2> 113067 INFO (jetty-closer-108-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 113067 ERROR (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-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> 113067 INFO (SUITE-RulesTest-seed#[7F5C8BB89524CFC8]-worker)
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44155 44155
[junit4] 2> 113187 INFO (Thread-58) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:44155 44155
[junit4] 2> 113188 WARN (Thread-58) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/aliases.json
[junit4] 2> 17 /solr/collections/rulesColl/terms/shard2
[junit4] 2> 10 /solr/configs/conf
[junit4] 2> 8 /solr/collections/modifyColl/terms/shard1
[junit4] 2> 8 /solr/collections/hostFragment/terms/shard1
[junit4] 2> 7 /solr/collections/rulesColl/terms/shard1
[junit4] 2> 6 /solr/collections/portRuleColl/terms/shard1
[junit4] 2> 5 /solr/security.json
[junit4] 2> 3 /solr/collections/portRuleColl2/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 28 /solr/aliases.json
[junit4] 2> 18 /solr/clusterprops.json
[junit4] 2> 18 /solr/clusterstate.json
[junit4] 2> 13 /solr/collections/rulesColl/state.json
[junit4] 2> 3 /solr/collections/portRuleColl/state.json
[junit4] 2> 3
/solr/overseer_elect/election/72176993231110154-127.0.0.1:41809_solr-n_0000000000
[junit4] 2> 2 /solr/collections/modifyColl/state.json
[junit4] 2> 2 /solr/collections/hostFragment/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 53 /solr/collections
[junit4] 2> 18 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.rule.RulesTest_7F5C8BB89524CFC8-001
[junit4] 2> Sep 06, 2018 9:31:18 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are:
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=2166, maxDocsPerChunk=7, blockSize=4),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=2166, blockSize=4)), sim=RandomSimilarity(queryNorm=true): {},
locale=sw, timezone=Libya
[junit4] 2> NOTE: Linux 4.15.0-32-generic amd64/Oracle Corporation 11
(64-bit)/cpus=8,threads=1,free=202635040,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [RulesTest, RulesTest]
[junit4] Completed [5/5 (2!)] on J0 in 70.02s, 7 tests, 1 error <<< FAILURES!
[...truncated 12 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1567: The
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1092:
There were test failures: 5 suites, 35 tests, 2 errors [seed: 7F5C8BB89524CFC8]
Total time: 1 minute 56 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.cloud.rule.RulesTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=a8129eb4f37cd69bd8b253045b801e85beabfcd2,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux
[WARNINGS] Computing warning deltas based on reference build #2695
Recording test results
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
