Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/75/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseSerialGC
1 tests failed.
FAILED:
org.apache.solr.common.cloud.TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure
Error Message:
Did not see a fully active cluster after 30 seconds
Stack Trace:
java.lang.AssertionError: Did not see a fully active cluster after 30 seconds
at
__randomizedtesting.SeedInfo.seed([BF613E15ED75B286:37579C4635DA5A94]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.common.cloud.TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure(TestCollectionStateWatchers.java:250)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14108 lines...]
[junit4] Suite: org.apache.solr.common.cloud.TestCollectionStateWatchers
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/init-core-data-001
[junit4] 2> 120646 WARN
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 120646 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 120648 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 120648 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001
[junit4] 2> 120648 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 120649 INFO (Thread-221) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 120649 INFO (Thread-221) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 120650 ERROR (Thread-221) [ ] 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> 120749 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:38123
[junit4] 2> 120754 INFO (jetty-launcher-187-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 120754 INFO (jetty-launcher-187-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 120755 INFO (jetty-launcher-187-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 120756 INFO (jetty-launcher-187-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 120757 INFO (jetty-launcher-187-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7495e6{/solr,null,AVAILABLE}
[junit4] 2> 120759 INFO (jetty-launcher-187-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1b45c20{SSL,[ssl,
http/1.1]}{127.0.0.1:39431}
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1545226{/solr,null,AVAILABLE}
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-4) [ ]
o.e.j.s.Server Started @122545ms
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39431}
[junit4] 2> 120760 ERROR (jetty-launcher-187-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-21T11:14:47.961Z
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1ab8fdd{/solr,null,AVAILABLE}
[junit4] 2> 120760 INFO (jetty-launcher-187-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@12de145{SSL,[ssl,
http/1.1]}{127.0.0.1:45271}
[junit4] 2> 120761 INFO (jetty-launcher-187-thread-3) [ ]
o.e.j.s.Server Started @122546ms
[junit4] 2> 120761 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45271}
[junit4] 2> 120761 ERROR (jetty-launcher-187-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 120761 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 120761 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 120761 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 120761 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-21T11:14:47.962Z
[junit4] 2> 120767 INFO (jetty-launcher-187-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@183f939{SSL,[ssl,
http/1.1]}{127.0.0.1:35225}
[junit4] 2> 120767 INFO (jetty-launcher-187-thread-1) [ ]
o.e.j.s.Server Started @122553ms
[junit4] 2> 120767 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35225}
[junit4] 2> 120767 ERROR (jetty-launcher-187-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 120767 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 120767 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 120768 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 120768 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-21T11:14:47.969Z
[junit4] 2> 120775 INFO (jetty-launcher-187-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@17abec9{/solr,null,AVAILABLE}
[junit4] 2> 120775 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 120775 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 120779 INFO (jetty-launcher-187-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@2b596{SSL,[ssl,
http/1.1]}{127.0.0.1:42273}
[junit4] 2> 120779 INFO (jetty-launcher-187-thread-2) [ ]
o.e.j.s.Server Started @122565ms
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42273}
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 120780 ERROR (jetty-launcher-187-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-21T11:14:47.981Z
[junit4] 2> 120780 INFO (jetty-launcher-187-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38123/solr
[junit4] 2> 120779 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e04808faa0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 120787 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 120803 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 120809 INFO (jetty-launcher-187-thread-3)
[n:127.0.0.1:45271_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 120810 INFO (jetty-launcher-187-thread-3)
[n:127.0.0.1:45271_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:45271_solr
[junit4] 2> 120810 INFO (jetty-launcher-187-thread-3)
[n:127.0.0.1:45271_solr ] o.a.s.c.Overseer Overseer
(id=98521192061337606-127.0.0.1:45271_solr-n_0000000000) starting
[junit4] 2> 120811 INFO (jetty-launcher-187-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38123/solr
[junit4] 2> 120814 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 120814 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e04808faa0003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 120826 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 120826 INFO (jetty-launcher-187-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38123/solr
[junit4] 2> 120833 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e04808faa0005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 120835 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 120837 INFO (jetty-launcher-187-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38123/solr
[junit4] 2> 120839 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e04808faa0008, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 120851 INFO (jetty-launcher-187-thread-3)
[n:127.0.0.1:45271_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45271_solr
[junit4] 2> 120863 INFO
(zkCallback-197-thread-1-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 120866 INFO (jetty-launcher-187-thread-4)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 120870 INFO (jetty-launcher-187-thread-4)
[n:127.0.0.1:39431_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 120871 INFO (jetty-launcher-187-thread-1)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 120872 INFO (jetty-launcher-187-thread-2)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 120874 INFO (jetty-launcher-187-thread-4)
[n:127.0.0.1:39431_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39431_solr
[junit4] 2> 120875 INFO (jetty-launcher-187-thread-1)
[n:127.0.0.1:35225_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 120876 INFO
(zkCallback-197-thread-1-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 120878 INFO (jetty-launcher-187-thread-2)
[n:127.0.0.1:42273_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 120882 INFO (jetty-launcher-187-thread-1)
[n:127.0.0.1:35225_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35225_solr
[junit4] 2> 120883 INFO (jetty-launcher-187-thread-2)
[n:127.0.0.1:42273_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42273_solr
[junit4] 2> 120889 INFO
(zkCallback-209-thread-1-processing-n:127.0.0.1:35225_solr)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 120889 INFO
(zkCallback-205-thread-1-processing-n:127.0.0.1:39431_solr)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 120889 INFO
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 120894 INFO
(zkCallback-209-thread-1-processing-n:127.0.0.1:35225_solr)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 120894 INFO
(zkCallback-205-thread-1-processing-n:127.0.0.1:39431_solr)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 120894 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 120894 INFO
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 120899 INFO
(zkCallback-205-thread-1-processing-n:127.0.0.1:39431_solr)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 120899 INFO
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 120903 INFO
(zkCallback-197-thread-1-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 120903 INFO
(zkCallback-209-thread-1-processing-n:127.0.0.1:35225_solr)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 120986 INFO (jetty-launcher-187-thread-4)
[n:127.0.0.1:39431_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node4/.
[junit4] 2> 120992 INFO (jetty-launcher-187-thread-1)
[n:127.0.0.1:35225_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node1/.
[junit4] 2> 121007 INFO (jetty-launcher-187-thread-2)
[n:127.0.0.1:42273_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node2/.
[junit4] 2> 121054 INFO (jetty-launcher-187-thread-3)
[n:127.0.0.1:45271_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node3/.
[junit4] 2> 121060 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 121060 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38123/solr ready
[junit4] 2> 121075 INFO
(TEST-TestCollectionStateWatchers.testPredicateFailureTimesOut-seed#[BF613E15ED75B286])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testPredicateFailureTimesOut
[junit4] 2> 122084 INFO
(TEST-TestCollectionStateWatchers.testPredicateFailureTimesOut-seed#[BF613E15ED75B286])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testPredicateFailureTimesOut
[junit4] 2> 122106 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[BF613E15ED75B286])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testWaitForStateChecksCurrentState
[junit4] 2> 122174 INFO (qtp10928192-1117) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=2091befc-44bd-43e4-b892-e54fb8b7fc09&replicationFactor=1&collection.configName=config&name=waitforstate&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 122176 INFO (qtp10928192-1117) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=2091befc-44bd-43e4-b892-e54fb8b7fc09&replicationFactor=1&collection.configName=config&name=waitforstate&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 122178 INFO
(OverseerThreadFactory-642-thread-1-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.CreateCollectionCmd Create collection
waitforstate
[junit4] 2> 122183 INFO (qtp10928192-1132) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 122184 INFO (qtp10928192-1132) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 122366 INFO (qtp33021321-1110) [n:127.0.0.1:45271_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498&qt=/admin/cores&collection.configName=config&newCollection=true&name=waitforstate_shard1_replica1&action=CREATE&numShards=1&collection=waitforstate&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 122366 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498&qt=/admin/cores&collection.configName=config&newCollection=true&name=waitforstate_shard1_replica1&action=CREATE&numShards=1&collection=waitforstate&shard=shard1&wt=javabin&version=2
[junit4] 2> 122367 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 122369 INFO (qtp33021321-1118) [n:127.0.0.1:45271_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498
[junit4] 2> 122369 INFO (qtp33021321-1118) [n:127.0.0.1:45271_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 122471 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/waitforstate/state.json] for collection [waitforstate] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 123187 INFO (qtp10928192-1133) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 123188 INFO (qtp10928192-1133) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 123372 INFO (qtp33021321-1120) [n:127.0.0.1:45271_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498
[junit4] 2> 123372 INFO (qtp33021321-1120) [n:127.0.0.1:45271_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 123375 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 123381 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema
[waitforstate_shard1_replica1] Schema name=test
[junit4] 2> 123423 WARN
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not
multivalued and destination for multiple copyFields (2)
[junit4] 2> 123424 WARN
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema Field text is not
multivalued and destination for multiple copyFields (3)
[junit4] 2> 123424 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema Loaded schema test/1.6 with
uniqueid field id
[junit4] 2> 123424 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore
'waitforstate_shard1_replica1' using configuration from collection
waitforstate, trusted=true
[junit4] 2> 123424 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 123424 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.SolrCore
[[waitforstate_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node3/waitforstate_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node3/./waitforstate_shard1_replica1/data/]
[junit4] 2> 123446 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 123446 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 123446 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 123446 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 123447 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@7c7f2a[waitforstate_shard1_replica1] main]
[junit4] 2> 123448 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 123448 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 123448 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 123448 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1576339075916365824
[junit4] 2> 123448 INFO
(searcherExecutor-651-thread-1-processing-x:waitforstate_shard1_replica1
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE
n:127.0.0.1:45271_solr s:shard1 c:waitforstate) [n:127.0.0.1:45271_solr
c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore
[waitforstate_shard1_replica1] Registered new searcher
Searcher@7c7f2a[waitforstate_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 123451 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 123451 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the
new leader - try and sync
[junit4] 2> 123451 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:45271/solr/waitforstate_shard1_replica1/
[junit4] 2> 123451 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 123452 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:45271/solr/waitforstate_shard1_replica1/ has no replicas
[junit4] 2> 123452 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 123453 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: https://127.0.0.1:45271/solr/waitforstate_shard1_replica1/ shard1
[junit4] 2> 123555 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/waitforstate/state.json] for collection [waitforstate] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 123604 INFO
(parallelCoreAdminExecutor-649-thread-1-processing-n:127.0.0.1:45271_solr
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498 CREATE)
[n:127.0.0.1:45271_solr c:waitforstate s:shard1
x:waitforstate_shard1_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 123708 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/waitforstate/state.json] for collection [waitforstate] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 124191 INFO (qtp10928192-1134) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 124192 INFO (qtp10928192-1134) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 124375 INFO (qtp33021321-1123) [n:127.0.0.1:45271_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498
[junit4] 2> 124375 INFO (qtp33021321-1123) [n:127.0.0.1:45271_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=2091befc-44bd-43e4-b892-e54fb8b7fc092311012488193498&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 124380 INFO
(OverseerThreadFactory-642-thread-1-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_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> 125194 INFO (qtp10928192-1117) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 125196 INFO (qtp10928192-1117) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 125198 INFO (qtp10928192-1132) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with
params
requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=DELETESTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 125199 INFO (qtp10928192-1132) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=2091befc-44bd-43e4-b892-e54fb8b7fc09&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 125202 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[BF613E15ED75B286])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testWaitForStateChecksCurrentState
[junit4] 2> 125214 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[BF613E15ED75B286])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testWaitForStateWatcherIsRetainedOnPredicateFailure
[junit4] 2> 125217 INFO (qtp10928192-1133) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&replicationFactor=1&collection.configName=config&name=falsepredicate&action=CREATE&numShards=4&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 125217 INFO (qtp10928192-1133) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&replicationFactor=1&collection.configName=config&name=falsepredicate&action=CREATE&numShards=4&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 125218 INFO
(OverseerThreadFactory-642-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.CreateCollectionCmd Create collection
falsepredicate
[junit4] 2> 125219 INFO
(OverseerCollectionConfigSetProcessor-98521192061337606-127.0.0.1:45271_solr-n_0000000000)
[n:127.0.0.1:45271_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> 125219 INFO (qtp10928192-1134) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 125220 INFO (qtp10928192-1134) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 125443 INFO (qtp10928192-1134) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard2_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard2&wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 125443 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard2_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard2&wt=javabin&version=2
[junit4] 2> 125443 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard3_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard3&wt=javabin&version=2
[junit4] 2> 125443 INFO (qtp33021321-1110) [n:127.0.0.1:45271_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard3_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard3&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 125444 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 125505 INFO (qtp3893133-1109) [n:127.0.0.1:35225_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard1_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 125505 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard1_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard1&wt=javabin&version=2
[junit4] 2> 125505 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 125509 INFO (qtp14269427-1119) [n:127.0.0.1:42273_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard4_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard4&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 125509 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616&qt=/admin/cores&collection.configName=config&newCollection=true&name=falsepredicate_shard4_replica1&action=CREATE&numShards=4&collection=falsepredicate&shard=shard4&wt=javabin&version=2
[junit4] 2> 125510 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 125512 INFO (qtp14269427-1146) [n:127.0.0.1:42273_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616
[junit4] 2> 125512 INFO (qtp14269427-1146) [n:127.0.0.1:42273_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 125615 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 125615 INFO
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 125615 INFO
(zkCallback-205-thread-1-processing-n:127.0.0.1:39431_solr)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 125615 INFO
(zkCallback-209-thread-1-processing-n:127.0.0.1:35225_solr)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126222 INFO (qtp10928192-1117) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 126224 INFO (qtp10928192-1117) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 126477 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 126478 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 126490 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard3_replica1] Schema name=test
[junit4] 2> 126490 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard2_replica1] Schema name=test
[junit4] 2> 126522 INFO (qtp14269427-1147) [n:127.0.0.1:42273_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616
[junit4] 2> 126522 INFO (qtp14269427-1147) [n:127.0.0.1:42273_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 126522 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 126530 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 126535 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard1_replica1] Schema name=test
[junit4] 2> 126536 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard4_replica1] Schema name=test
[junit4] 2> 126579 WARN
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is
not multivalued and destination for multiple copyFields (2)
[junit4] 2> 126579 WARN
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.IndexSchema Field text is not
multivalued and destination for multiple copyFields (3)
[junit4] 2> 126580 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.IndexSchema Loaded schema test/1.6
with uniqueid field id
[junit4] 2> 126580 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore
'falsepredicate_shard2_replica1' using configuration from collection
falsepredicate, trusted=true
[junit4] 2> 126580 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 126580 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore
[[falsepredicate_shard2_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node4/falsepredicate_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node4/./falsepredicate_shard2_replica1/data/]
[junit4] 2> 126581 WARN
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is
not multivalued and destination for multiple copyFields (2)
[junit4] 2> 126581 WARN
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.IndexSchema Field text is not
multivalued and destination for multiple copyFields (3)
[junit4] 2> 126581 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.IndexSchema Loaded schema test/1.6
with uniqueid field id
[junit4] 2> 126581 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.CoreContainer Creating SolrCore
'falsepredicate_shard3_replica1' using configuration from collection
falsepredicate, trusted=true
[junit4] 2> 126582 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 126582 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SolrCore
[[falsepredicate_shard3_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node3/falsepredicate_shard3_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node3/./falsepredicate_shard3_replica1/data/]
[junit4] 2> 126602 WARN
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is
not multivalued and destination for multiple copyFields (2)
[junit4] 2> 126602 WARN
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.IndexSchema Field text is not
multivalued and destination for multiple copyFields (3)
[junit4] 2> 126603 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.IndexSchema Loaded schema test/1.6
with uniqueid field id
[junit4] 2> 126603 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.CoreContainer Creating SolrCore
'falsepredicate_shard4_replica1' using configuration from collection
falsepredicate, trusted=true
[junit4] 2> 126603 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 126603 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore
[[falsepredicate_shard4_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node2/falsepredicate_shard4_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node2/./falsepredicate_shard4_replica1/data/]
[junit4] 2> 126615 WARN
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is
not multivalued and destination for multiple copyFields (2)
[junit4] 2> 126615 WARN
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.IndexSchema Field text is not
multivalued and destination for multiple copyFields (3)
[junit4] 2> 126615 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.IndexSchema Loaded schema test/1.6
with uniqueid field id
[junit4] 2> 126615 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore
'falsepredicate_shard1_replica1' using configuration from collection
falsepredicate, trusted=true
[junit4] 2> 126616 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 126616 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SolrCore
[[falsepredicate_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node1/falsepredicate_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001/tempDir-001/node1/./falsepredicate_shard1_replica1/data/]
[junit4] 2> 126622 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 126622 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 126622 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 126622 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 126623 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1d56115[falsepredicate_shard2_replica1] main]
[junit4] 2> 126623 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 126623 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 126624 INFO
(searcherExecutor-656-thread-1-processing-x:falsepredicate_shard2_replica1
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE
n:127.0.0.1:39431_solr s:shard2 c:falsepredicate) [n:127.0.0.1:39431_solr
c:falsepredicate s:shard2 x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore
[falsepredicate_shard2_replica1] Registered new searcher
Searcher@1d56115[falsepredicate_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.UpdateLog Could not find max version
in index or recent updates, using new clock 1576339079246643200
[junit4] 2> 126624 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1eb8178[falsepredicate_shard3_replica1] main]
[junit4] 2> 126625 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 126625 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 126625 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 126625 INFO
(searcherExecutor-657-thread-1-processing-x:falsepredicate_shard3_replica1
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE
n:127.0.0.1:45271_solr s:shard3 c:falsepredicate) [n:127.0.0.1:45271_solr
c:falsepredicate s:shard3 x:falsepredicate_shard3_replica1] o.a.s.c.SolrCore
[falsepredicate_shard3_replica1] Registered new searcher
Searcher@1eb8178[falsepredicate_shard3_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 126625 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.UpdateLog Could not find max version
in index or recent updates, using new clock 1576339079247691776
[junit4] 2> 126628 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 126628 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I may be
the new leader - try and sync
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:39431/solr/falsepredicate_shard2_replica1/
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:39431/solr/falsepredicate_shard2_replica1/ has no replicas
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext I may be
the new leader - try and sync
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:45271/solr/falsepredicate_shard3_replica1/
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:45271/solr/falsepredicate_shard3_replica1/ has no replicas
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 126629 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@134c2d[falsepredicate_shard4_replica1] main]
[junit4] 2> 126630 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 126630 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 126630 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 126630 INFO
(searcherExecutor-658-thread-1-processing-x:falsepredicate_shard4_replica1
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE
n:127.0.0.1:42273_solr s:shard4 c:falsepredicate) [n:127.0.0.1:42273_solr
c:falsepredicate s:shard4 x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore
[falsepredicate_shard4_replica1] Registered new searcher
Searcher@134c2d[falsepredicate_shard4_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 126630 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.UpdateLog Could not find max version
in index or recent updates, using new clock 1576339079252934656
[junit4] 2> 126632 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:39431/solr/falsepredicate_shard2_replica1/ shard2
[junit4] 2> 126632 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:45271/solr/falsepredicate_shard3_replica1/ shard3
[junit4] 2> 126634 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 126634 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext I may be
the new leader - try and sync
[junit4] 2> 126634 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:42273/solr/falsepredicate_shard4_replica1/
[junit4] 2> 126634 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 126634 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:42273/solr/falsepredicate_shard4_replica1/ has no replicas
[junit4] 2> 126634 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 126636 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:42273/solr/falsepredicate_shard4_replica1/ shard4
[junit4] 2> 126638 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 126638 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 126638 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 126638 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 126639 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@13feb68[falsepredicate_shard1_replica1] main]
[junit4] 2> 126639 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 126639 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 126639 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 126639 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.UpdateLog Could not find max version
in index or recent updates, using new clock 1576339079262371840
[junit4] 2> 126639 INFO
(searcherExecutor-661-thread-1-processing-x:falsepredicate_shard1_replica1
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE
n:127.0.0.1:35225_solr s:shard1 c:falsepredicate) [n:127.0.0.1:35225_solr
c:falsepredicate s:shard1 x:falsepredicate_shard1_replica1] o.a.s.c.SolrCore
[falsepredicate_shard1_replica1] Registered new searcher
Searcher@13feb68[falsepredicate_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 126642 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 126642 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be
the new leader - try and sync
[junit4] 2> 126642 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:35225/solr/falsepredicate_shard1_replica1/
[junit4] 2> 126642 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 126642 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:35225/solr/falsepredicate_shard1_replica1/ has no replicas
[junit4] 2> 126642 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 126643 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:35225/solr/falsepredicate_shard1_replica1/ shard1
[junit4] 2> 126745 INFO
(zkCallback-209-thread-1-processing-n:127.0.0.1:35225_solr)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126745 INFO
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126745 INFO
(zkCallback-205-thread-1-processing-n:127.0.0.1:39431_solr)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126745 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126783 INFO
(parallelCoreAdminExecutor-649-thread-2-processing-n:127.0.0.1:45271_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628881570 CREATE)
[n:127.0.0.1:45271_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 126783 INFO
(parallelCoreAdminExecutor-643-thread-1-processing-n:127.0.0.1:39431_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628395878 CREATE)
[n:127.0.0.1:39431_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 126787 INFO
(parallelCoreAdminExecutor-647-thread-1-processing-n:127.0.0.1:42273_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616 CREATE)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 126794 INFO
(parallelCoreAdminExecutor-644-thread-1-processing-n:127.0.0.1:35225_solr
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141 CREATE)
[n:127.0.0.1:35225_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 126897 INFO
(zkCallback-209-thread-1-processing-n:127.0.0.1:35225_solr)
[n:127.0.0.1:35225_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126897 INFO
(zkCallback-197-thread-2-processing-n:127.0.0.1:45271_solr)
[n:127.0.0.1:45271_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126897 INFO
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 126897 INFO
(zkCallback-205-thread-1-processing-n:127.0.0.1:39431_solr)
[n:127.0.0.1:39431_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/falsepredicate/state.json] for collection [falsepredicate]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 127226 INFO (qtp10928192-1122) [n:127.0.0.1:39431_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 127227 INFO (qtp10928192-1122) [n:127.0.0.1:39431_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e11&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 127525 INFO (qtp14269427-1149) [n:127.0.0.1:42273_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616
[junit4] 2> 127525 INFO (qtp14269427-1149) [n:127.0.0.1:42273_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628654616&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 127530 INFO (qtp3893133-1137) [n:127.0.0.1:35225_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
39703cfc-eff5-4e67-9a26-c6ca69cd6e112311015628254141
[junit4] 2> 127530 INFO (qtp3893133-1137) [n:127.0.0.1:35225_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={
[...truncated too long message...]
or-746-thread-2) [n:127.0.0.1:42273_solr c:currentstate s:shard1 r:core_node1
x:currentstate_shard1_replica1] o.a.s.c.SolrCore [currentstate_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@60498b
[junit4] 2> 177751 INFO (coreCloseExecutor-747-thread-1)
[n:127.0.0.1:34643_solr c:falsepredicate s:shard2 r:core_node5
x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore
[falsepredicate_shard2_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1f1f0d8
[junit4] 2> 177749 INFO (coreCloseExecutor-748-thread-2)
[n:127.0.0.1:34843_solr c:stateformat1 s:shard1 r:core_node1
x:stateformat1_shard1_replica1] o.a.s.c.SolrCore [stateformat1_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@ce3a12
[junit4] 2> 177748 INFO (coreCloseExecutor-748-thread-3)
[n:127.0.0.1:34843_solr c:testcollection s:shard4 r:core_node4
x:testcollection_shard4_replica1] o.a.s.c.SolrCore
[testcollection_shard4_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1fd3f82
[junit4] 2> 177748 INFO (coreCloseExecutor-748-thread-1)
[n:127.0.0.1:34843_solr c:falsepredicate s:shard1 r:core_node6
x:falsepredicate_shard1_replica1] o.a.s.c.SolrCore
[falsepredicate_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@17c3218
[junit4] 2> 177748 INFO (coreCloseExecutor-746-thread-1)
[n:127.0.0.1:42273_solr c:falsepredicate s:shard4 r:core_node4
x:falsepredicate_shard4_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard4.replica1
[junit4] 2> 177747 INFO (coreCloseExecutor-746-thread-3)
[n:127.0.0.1:42273_solr c:testcollection s:shard1 r:core_node1
x:testcollection_shard1_replica1] o.a.s.c.SolrCore
[testcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1dad67a
[junit4] 2> 177747 INFO (coreCloseExecutor-747-thread-2)
[n:127.0.0.1:34643_solr c:testcollection s:shard3 r:core_node3
x:testcollection_shard3_replica1] o.a.s.c.SolrCore
[testcollection_shard3_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@a21493
[junit4] 2> 177751 INFO (coreCloseExecutor-748-thread-1)
[n:127.0.0.1:34843_solr c:falsepredicate s:shard1 r:core_node6
x:falsepredicate_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard1.replica1
[junit4] 2> 177751 INFO (coreCloseExecutor-746-thread-2)
[n:127.0.0.1:42273_solr c:currentstate s:shard1 r:core_node1
x:currentstate_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.currentstate.shard1.replica1
[junit4] 2> 177751 INFO (coreCloseExecutor-748-thread-3)
[n:127.0.0.1:34843_solr c:testcollection s:shard4 r:core_node4
x:testcollection_shard4_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.testcollection.shard4.replica1
[junit4] 2> 177752 INFO (coreCloseExecutor-747-thread-2)
[n:127.0.0.1:34643_solr c:testcollection s:shard3 r:core_node3
x:testcollection_shard3_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.testcollection.shard3.replica1
[junit4] 2> 177754 INFO (coreCloseExecutor-748-thread-2)
[n:127.0.0.1:34843_solr c:stateformat1 s:shard1 r:core_node1
x:stateformat1_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.stateformat1.shard1.replica1
[junit4] 2> 177754 INFO (coreCloseExecutor-746-thread-3)
[n:127.0.0.1:42273_solr c:testcollection s:shard1 r:core_node1
x:testcollection_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.testcollection.shard1.replica1
[junit4] 2> 177754 INFO (coreCloseExecutor-747-thread-1)
[n:127.0.0.1:34643_solr c:falsepredicate s:shard2 r:core_node5
x:falsepredicate_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard2.replica1
[junit4] 2> 177755 INFO (jetty-closer-188-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98521192061337612-127.0.0.1:42273_solr-n_0000000003) closing
[junit4] 2> 177755 INFO
(OverseerStateUpdate-98521192061337612-127.0.0.1:42273_solr-n_0000000003)
[n:127.0.0.1:42273_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:42273_solr
[junit4] 2> 177758 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e04808faa000c, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 177759 WARN
(zkCallback-231-thread-2-processing-n:127.0.0.1:34843_solr)
[n:127.0.0.1:34843_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 177763 INFO (jetty-closer-188-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@53bb08{/solr,null,UNAVAILABLE}
[junit4] 2> 179258 WARN
(zkCallback-210-thread-1-processing-n:127.0.0.1:42273_solr)
[n:127.0.0.1:42273_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 179258 WARN
(zkCallback-225-thread-1-processing-n:127.0.0.1:34643_solr)
[n:127.0.0.1:34643_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 179258 INFO (jetty-closer-188-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@17abec9{/solr,null,UNAVAILABLE}
[junit4] 2> 179258 INFO (jetty-closer-188-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1115f7{/solr,null,UNAVAILABLE}
[junit4] 2> 179259 ERROR
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-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> 179259 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:38123 38123
[junit4] 2> 185657 INFO (Thread-221) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:38123 38123
[junit4] 2> 185658 WARN (Thread-221) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2> 6 /solr/configs/config
[junit4] 2> 3 /solr/collections/stateformat1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/waitforstate/state.json
[junit4] 2> 7 /solr/collections/falsepredicate/state.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 7 /solr/collections/testcollection/state.json
[junit4] 2> 3
/solr/overseer_elect/election/98521192061337606-127.0.0.1:45271_solr-n_0000000000
[junit4] 2> 3 /solr/collections/stateformat1/state.json
[junit4] 2> 2 /solr/collections/currentstate/state.json
[junit4] 2> 2 /solr/collections/delayed/state.json
[junit4] 2> 2 /solr/collections/tobedeleted/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 185658 INFO
(SUITE-TestCollectionStateWatchers-seed#[BF613E15ED75B286]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_BF613E15ED75B286-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62),
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ga-IE,
timezone=Asia/Pontianak
[junit4] 2> NOTE: Linux 4.10.0-27-generic i386/Oracle Corporation
1.8.0_144 (32-bit)/cpus=8,threads=1,free=22396888,total=221167616
[junit4] 2> NOTE: All tests run in this JVM: [OperationsTest,
TestSolrJErrorHandling, JdbcDriverTest, ConcurrentUpdateSolrClientBuilderTest,
DocumentAnalysisResponseTest, CosineEvaluatorTest, SolrExampleEmbeddedTest,
TestDocumentObjectBinder, SolrExampleStreamingBinaryTest, FloorEvaluatorTest,
LessThanEqualToEvaluatorTest, CoalesceEvaluatorTest, MultiplyEvaluatorTest,
OrEvaluatorTest, ClientUtilsTest, SolrZkClientTest, QueryResponseTest,
SubtractEvaluatorTest, CloudSolrClientCacheTest, SolrQueryTest,
TestCloudSolrClientConnections, SolrExampleBinaryTest, LargeVolumeJettyTest,
TestSolrProperties, SolrParamTest, TermsResponseTest, MergeIndexesEmbeddedTest,
TestEmbeddedSolrServer, TestCoreAdmin, TestNamedListCodec, TestHash,
ContentStreamTest, ModifiableSolrParamsTest, TestFastInputStream,
SolrDocumentTest, FieldAnalysisResponseTest, NamedListTest, GetByIdTest,
SolrSchemalessExampleTest, CloudSolrClientBuilderTest,
ConcurrentUpdateSolrClientTest, LBHttpSolrClientBuilderTest, GraphTest,
SelectWithEvaluatorsTest, StreamExpressionToExplanationTest, AndEvaluatorTest,
CeilingEvaluatorTest, EqualsEvaluatorTest, FieldEvaluatorTest,
HyperbolicCosineEvaluatorTest, HyperbolicTangentEvaluatorTest,
ModuloEvaluatorTest, RoundEvaluatorTest, SquareRootEvaluatorTest,
UuidEvaluatorTest, SolrPingTest, TestDelegationTokenResponse,
TestToleratedUpdateError, TestCollectionStateWatchers]
[junit4] Completed [130/130 (1!)] on J1 in 65.03s, 9 tests, 1 failure <<<
FAILURES!
[...truncated 38682 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
