Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/72/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
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([22607AD7AE46FF01:AA56D88476E91713]: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 14112 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_22607AD7AE46FF01-001/init-core-data-001
[junit4] 2> 130995 WARN
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=19 numCloses=19
[junit4] 2> 130995 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 130996 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-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> 130997 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-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_22607AD7AE46FF01-001/tempDir-001
[junit4] 2> 130997 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 130997 INFO (Thread-305) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 130997 INFO (Thread-305) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 131002 ERROR (Thread-305) [ ] 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> 131097 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:35253
[junit4] 2> 131101 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 0x15e017e1fee0000, 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> 131104 INFO (jetty-launcher-307-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 131104 INFO (jetty-launcher-307-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 131104 INFO (jetty-launcher-307-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 131104 INFO (jetty-launcher-307-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 131105 INFO (jetty-launcher-307-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@60eca5c7{/solr,null,AVAILABLE}
[junit4] 2> 131105 INFO (jetty-launcher-307-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@523ec670{/solr,null,AVAILABLE}
[junit4] 2> 131106 INFO (jetty-launcher-307-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1556dfc{SSL,[ssl,
http/1.1]}{127.0.0.1:42609}
[junit4] 2> 131106 INFO (jetty-launcher-307-thread-1) [ ]
o.e.j.s.Server Started @133815ms
[junit4] 2> 131106 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42609}
[junit4] 2> 131106 ERROR (jetty-launcher-307-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 131107 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 131107 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 131107 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 131107 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-20T21:13:16.635Z
[junit4] 2> 131108 INFO (jetty-launcher-307-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@72ee51dc{SSL,[ssl,
http/1.1]}{127.0.0.1:38091}
[junit4] 2> 131112 INFO (jetty-launcher-307-thread-4) [ ]
o.e.j.s.Server Started @133817ms
[junit4] 2> 131112 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38091}
[junit4] 2> 131113 ERROR (jetty-launcher-307-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 131113 INFO (jetty-launcher-307-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7b92f622{/solr,null,AVAILABLE}
[junit4] 2> 131113 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 131113 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 131113 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 131113 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-20T21:13:16.641Z
[junit4] 2> 131123 INFO (jetty-launcher-307-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@32fe1d36{/solr,null,AVAILABLE}
[junit4] 2> 131123 INFO (jetty-launcher-307-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@158e95f3{SSL,[ssl,
http/1.1]}{127.0.0.1:35645}
[junit4] 2> 131123 INFO (jetty-launcher-307-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1c260506{SSL,[ssl,
http/1.1]}{127.0.0.1:40319}
[junit4] 2> 131123 INFO (jetty-launcher-307-thread-2) [ ]
o.e.j.s.Server Started @133832ms
[junit4] 2> 131123 INFO (jetty-launcher-307-thread-3) [ ]
o.e.j.s.Server Started @133832ms
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35645}
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40319}
[junit4] 2> 131124 ERROR (jetty-launcher-307-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 131124 ERROR (jetty-launcher-307-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-20T21:13:16.652Z
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 131124 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-20T21:13:16.652Z
[junit4] 2> 131127 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 131146 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 131147 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 131147 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 131147 INFO (jetty-launcher-307-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35253/solr
[junit4] 2> 131151 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 131153 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 131153 INFO (jetty-launcher-307-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35253/solr
[junit4] 2> 131157 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 131158 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 131164 INFO (jetty-launcher-307-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35253/solr
[junit4] 2> 131168 INFO (jetty-launcher-307-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35253/solr
[junit4] 2> 131179 INFO (jetty-launcher-307-thread-1)
[n:127.0.0.1:42609_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 131181 INFO (jetty-launcher-307-thread-1)
[n:127.0.0.1:42609_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42609_solr
[junit4] 2> 131182 INFO (jetty-launcher-307-thread-1)
[n:127.0.0.1:42609_solr ] o.a.s.c.Overseer Overseer
(id=98517883061927944-127.0.0.1:42609_solr-n_0000000000) starting
[junit4] 2> 131189 INFO (jetty-launcher-307-thread-3)
[n:127.0.0.1:40319_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 131190 INFO (jetty-launcher-307-thread-3)
[n:127.0.0.1:40319_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40319_solr
[junit4] 2> 131190 INFO (jetty-launcher-307-thread-2)
[n:127.0.0.1:35645_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 131192 INFO (jetty-launcher-307-thread-4)
[n:127.0.0.1:38091_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 131193 INFO (jetty-launcher-307-thread-4)
[n:127.0.0.1:38091_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38091_solr
[junit4] 2> 131212 INFO
(zkCallback-330-thread-1-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 131212 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 131212 INFO
(zkCallback-326-thread-1-processing-n:127.0.0.1:35645_solr)
[n:127.0.0.1:35645_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 131212 INFO
(OverseerStateUpdate-98517883061927944-127.0.0.1:42609_solr-n_0000000000)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 131213 INFO (jetty-launcher-307-thread-1)
[n:127.0.0.1:42609_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42609_solr
[junit4] 2> 131213 INFO (jetty-launcher-307-thread-2)
[n:127.0.0.1:35645_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35645_solr
[junit4] 2> 131214 INFO
(zkCallback-330-thread-1-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 131214 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 131218 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 131219 INFO
(zkCallback-326-thread-1-processing-n:127.0.0.1:35645_solr)
[n:127.0.0.1:35645_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 131243 INFO (jetty-launcher-307-thread-3)
[n:127.0.0.1:40319_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_22607AD7AE46FF01-001/tempDir-001/node3/.
[junit4] 2> 131250 INFO (jetty-launcher-307-thread-4)
[n:127.0.0.1:38091_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_22607AD7AE46FF01-001/tempDir-001/node4/.
[junit4] 2> 131332 INFO (jetty-launcher-307-thread-2)
[n:127.0.0.1:35645_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_22607AD7AE46FF01-001/tempDir-001/node2/.
[junit4] 2> 131338 INFO (jetty-launcher-307-thread-1)
[n:127.0.0.1:42609_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_22607AD7AE46FF01-001/tempDir-001/node1/.
[junit4] 2> 131383 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 131396 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35253/solr ready
[junit4] 2> 131424 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testWaitForStateWatcherIsRetainedOnPredicateFailure
[junit4] 2> 131497 INFO (qtp1267948898-1545) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&replicationFactor=1&collection.configName=config&name=falsepredicate&action=CREATE&numShards=4&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 131502 INFO (qtp1267948898-1545) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&replicationFactor=1&collection.configName=config&name=falsepredicate&action=CREATE&numShards=4&wt=javabin&version=2}
status=0 QTime=5
[junit4] 2> 131504 INFO (qtp1267948898-1526) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 131505 INFO (qtp1267948898-1526) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 131505 INFO
(OverseerThreadFactory-749-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.CreateCollectionCmd Create collection
falsepredicate
[junit4] 2> 131726 INFO (qtp1267948898-1531) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850&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=58
[junit4] 2> 131726 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850&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> 131740 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 131796 INFO (qtp1951510531-1522) [n:127.0.0.1:40319_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060&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=20
[junit4] 2> 131796 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060&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> 131796 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 131808 INFO (qtp1435546065-1552) [n:127.0.0.1:35645_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091&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=2
[junit4] 2> 131808 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091&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> 131808 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 131809 INFO (qtp161846229-1555) [n:127.0.0.1:42609_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165&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=0
[junit4] 2> 131809 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165&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> 131809 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 131811 INFO (qtp1267948898-1542) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850
[junit4] 2> 131811 INFO (qtp1267948898-1542) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 131918 INFO
(zkCallback-326-thread-1-processing-n:127.0.0.1:35645_solr)
[n:127.0.0.1:35645_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> 131918 INFO
(zkCallback-330-thread-1-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_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> 131918 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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> 131918 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_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> 132507 INFO (qtp1267948898-1540) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 132507 INFO (qtp1267948898-1540) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 132763 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 132776 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard1_replica1] Schema name=test
[junit4] 2> 132813 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 132814 INFO (qtp1267948898-1542) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850
[junit4] 2> 132814 INFO (qtp1267948898-1542) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 132815 WARN
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132815 WARN
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132815 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132815 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132815 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 132815 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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_22607AD7AE46FF01-001/tempDir-001/node4/falsepredicate_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_22607AD7AE46FF01-001/tempDir-001/node4/./falsepredicate_shard1_replica1/data/]
[junit4] 2> 132820 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard2_replica1] Schema name=test
[junit4] 2> 132822 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 132835 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 132854 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard3_replica1] Schema name=test
[junit4] 2> 132854 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard4_replica1] Schema name=test
[junit4] 2> 132875 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 132875 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132876 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 132876 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 132877 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@47ebf94c[falsepredicate_shard1_replica1] main]
[junit4] 2> 132878 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 132879 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132879 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 132879 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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 1576286130963218432
[junit4] 2> 132881 INFO
(searcherExecutor-758-thread-1-processing-x:falsepredicate_shard1_replica1
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE
n:127.0.0.1:38091_solr s:shard1 c:falsepredicate) [n:127.0.0.1:38091_solr
c:falsepredicate s:shard1 x:falsepredicate_shard1_replica1] o.a.s.c.SolrCore
[falsepredicate_shard1_replica1] Registered new searcher
Searcher@47ebf94c[falsepredicate_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 132903 WARN
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132904 WARN
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132905 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132905 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132905 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 132905 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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_22607AD7AE46FF01-001/tempDir-001/node3/falsepredicate_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_22607AD7AE46FF01-001/tempDir-001/node3/./falsepredicate_shard2_replica1/data/]
[junit4] 2> 132913 WARN
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132914 WARN
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132914 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132914 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132914 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 132914 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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_22607AD7AE46FF01-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_22607AD7AE46FF01-001/tempDir-001/node2/./falsepredicate_shard4_replica1/data/]
[junit4] 2> 132921 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 132921 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_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> 132921 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:38091/solr/falsepredicate_shard1_replica1/
[junit4] 2> 132921 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 132921 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:38091/solr/falsepredicate_shard1_replica1/ has no replicas
[junit4] 2> 132921 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 132923 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:38091/solr/falsepredicate_shard1_replica1/ shard1
[junit4] 2> 132936 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 132936 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132937 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 132937 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 132938 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@2dc87a01[falsepredicate_shard2_replica1] main]
[junit4] 2> 132939 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 132940 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132940 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 132940 INFO
(searcherExecutor-763-thread-1-processing-x:falsepredicate_shard2_replica1
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE
n:127.0.0.1:40319_solr s:shard2 c:falsepredicate) [n:127.0.0.1:40319_solr
c:falsepredicate s:shard2 x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore
[falsepredicate_shard2_replica1] Registered new searcher
Searcher@2dc87a01[falsepredicate_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 132941 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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 1576286131028230144
[junit4] 2> 132954 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 132954 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_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> 132954 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:40319/solr/falsepredicate_shard2_replica1/
[junit4] 2> 132955 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 132955 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:40319/solr/falsepredicate_shard2_replica1/ has no replicas
[junit4] 2> 132955 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 132957 WARN
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132957 WARN
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132957 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132957 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132957 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 132957 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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_22607AD7AE46FF01-001/tempDir-001/node1/falsepredicate_shard3_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_22607AD7AE46FF01-001/tempDir-001/node1/./falsepredicate_shard3_replica1/data/]
[junit4] 2> 132958 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:40319/solr/falsepredicate_shard2_replica1/ shard2
[junit4] 2> 132967 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 132967 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132968 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 132968 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 132968 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@57cf85b0[falsepredicate_shard4_replica1] main]
[junit4] 2> 132969 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 132969 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132969 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 132969 INFO
(searcherExecutor-764-thread-1-processing-x:falsepredicate_shard4_replica1
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE
n:127.0.0.1:35645_solr s:shard4 c:falsepredicate) [n:127.0.0.1:35645_solr
c:falsepredicate s:shard4 x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore
[falsepredicate_shard4_replica1] Registered new searcher
Searcher@57cf85b0[falsepredicate_shard4_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 132969 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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 1576286131057590272
[junit4] 2> 132973 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 132973 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_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> 132973 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:35645/solr/falsepredicate_shard4_replica1/
[junit4] 2> 132973 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 132973 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:35645/solr/falsepredicate_shard4_replica1/ has no replicas
[junit4] 2> 132973 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 132975 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:35645/solr/falsepredicate_shard4_replica1/ shard4
[junit4] 2> 132979 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 132979 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132979 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 132979 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 132980 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@6a158c77[falsepredicate_shard3_replica1] main]
[junit4] 2> 132980 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 132980 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132980 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 132981 INFO
(searcherExecutor-769-thread-1-processing-x:falsepredicate_shard3_replica1
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE
n:127.0.0.1:42609_solr s:shard3 c:falsepredicate) [n:127.0.0.1:42609_solr
c:falsepredicate s:shard3 x:falsepredicate_shard3_replica1] o.a.s.c.SolrCore
[falsepredicate_shard3_replica1] Registered new searcher
Searcher@6a158c77[falsepredicate_shard3_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 132981 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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 1576286131070173184
[junit4] 2> 132983 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 132983 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_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> 132983 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:42609/solr/falsepredicate_shard3_replica1/
[junit4] 2> 132983 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 132983 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:42609/solr/falsepredicate_shard3_replica1/ has no replicas
[junit4] 2> 132983 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 132985 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: https://127.0.0.1:42609/solr/falsepredicate_shard3_replica1/ shard3
[junit4] 2> 133087 INFO
(zkCallback-330-thread-1-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_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> 133087 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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> 133087 INFO
(zkCallback-326-thread-1-processing-n:127.0.0.1:35645_solr)
[n:127.0.0.1:35645_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> 133087 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_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> 133109 INFO
(parallelCoreAdminExecutor-750-thread-1-processing-n:127.0.0.1:40319_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060 CREATE)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2
x:falsepredicate_shard2_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 133124 INFO
(parallelCoreAdminExecutor-752-thread-1-processing-n:127.0.0.1:38091_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850 CREATE)
[n:127.0.0.1:38091_solr c:falsepredicate s:shard1
x:falsepredicate_shard1_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 133125 INFO
(parallelCoreAdminExecutor-754-thread-1-processing-n:127.0.0.1:35645_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091 CREATE)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 133135 INFO
(parallelCoreAdminExecutor-755-thread-1-processing-n:127.0.0.1:42609_solr
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165 CREATE)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3
x:falsepredicate_shard3_replica1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 133239 INFO
(zkCallback-330-thread-1-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_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> 133239 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_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> 133239 INFO
(zkCallback-326-thread-1-processing-n:127.0.0.1:35645_solr)
[n:127.0.0.1:35645_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> 133240 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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> 133510 INFO (qtp1267948898-1526) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 133510 INFO (qtp1267948898-1526) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 133817 INFO (qtp1267948898-1542) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850
[junit4] 2> 133817 INFO (qtp1267948898-1542) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520144956850&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 133819 INFO (qtp1435546065-1529) [n:127.0.0.1:35645_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091
[junit4] 2> 133819 INFO (qtp1435546065-1529) [n:127.0.0.1:35645_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520184105091&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 133822 INFO (qtp161846229-1525) [n:127.0.0.1:42609_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165
[junit4] 2> 133822 INFO (qtp161846229-1525) [n:127.0.0.1:42609_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520192122165&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 133825 INFO (qtp1951510531-1532) [n:127.0.0.1:40319_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060
[junit4] 2> 133825 INFO (qtp1951510531-1532) [n:127.0.0.1:40319_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c82260520164123060&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 133830 INFO
(OverseerThreadFactory-749-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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> 134513 INFO (qtp1267948898-1545) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 134515 INFO (qtp1267948898-1545) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 134517 INFO (qtp1267948898-1531) [n:127.0.0.1:38091_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with
params
requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=DELETESTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 134518 INFO (qtp1267948898-1531) [n:127.0.0.1:38091_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=1ea3a18c-885a-44ba-864b-6bd223e6f4c8&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 134525 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@158e95f3{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 134525 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=64488250
[junit4] 2> 134526 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 134526 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 134526 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 134529 INFO (coreCloseExecutor-778-thread-1)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4 r:core_node3
x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore
[falsepredicate_shard4_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4f68912a
[junit4] 2> 134531 INFO (coreCloseExecutor-778-thread-1)
[n:127.0.0.1:35645_solr c:falsepredicate s:shard4 r:core_node3
x:falsepredicate_shard4_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard4.replica1
[junit4] 2> 134533 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 134533 INFO
(zkCallback-330-thread-1-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 134533 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 134534 INFO (zkCallback-337-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 134636 INFO
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_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: [3])
[junit4] 2> 134636 INFO
(zkCallback-330-thread-2-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_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: [3])
[junit4] 2> 134636 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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: [3])
[junit4] 2> 135506 INFO
(OverseerCollectionConfigSetProcessor-98517883061927944-127.0.0.1:42609_solr-n_0000000000)
[n:127.0.0.1:42609_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> 136033 WARN
(zkCallback-326-thread-1-processing-n:127.0.0.1:35645_solr)
[n:127.0.0.1:35645_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 136033 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7b92f622{/solr,null,UNAVAILABLE}
[junit4] 2> 136036 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1c260506{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 136037 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1945680571
[junit4] 2> 136037 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 136037 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 136037 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 136038 INFO (coreCloseExecutor-780-thread-1)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2 r:core_node2
x:falsepredicate_shard2_replica1] o.a.s.c.SolrCore
[falsepredicate_shard2_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4cbe6a9b
[junit4] 2> 136044 INFO (coreCloseExecutor-780-thread-1)
[n:127.0.0.1:40319_solr c:falsepredicate s:shard2 r:core_node2
x:falsepredicate_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard2.replica1
[junit4] 2> 136045 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 136048 INFO
(zkCallback-330-thread-2-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 136048 INFO (zkCallback-337-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 136142 INFO
(zkCallback-330-thread-2-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_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: [2])
[junit4] 2> 136142 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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: [2])
[junit4] 2> 136144 INFO (zkCallback-337-thread-1) [ ]
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: [2])
[junit4] 2> 137545 WARN
(zkCallback-328-thread-1-processing-n:127.0.0.1:40319_solr)
[n:127.0.0.1:40319_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 137546 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@32fe1d36{/solr,null,UNAVAILABLE}
[junit4] 2> 137547 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 137548 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@779f5868{/solr,null,AVAILABLE}
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@e50f524{SSL,[ssl,
http/1.1]}{127.0.0.1:42079}
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.e.j.s.Server Started @140257ms
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42079}
[junit4] 2> 137549 ERROR
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 6.6.1
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 137549 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-20T21:13:23.077Z
[junit4] 2> 137551 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 137560 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 137561 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35253/solr
[junit4] 2> 137568 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[n:127.0.0.1:42079_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 137570 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[n:127.0.0.1:42079_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 137571 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[n:127.0.0.1:42079_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42079_solr
[junit4] 2> 137572 INFO
(zkCallback-330-thread-2-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 137572 INFO (zkCallback-337-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 137576 INFO
(zkCallback-343-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 137576 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 137673 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[n:127.0.0.1:42079_solr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_22607AD7AE46FF01-001/tempDir-001/node2/.
[junit4] 2> 137673 INFO
(TEST-TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure-seed#[22607AD7AE46FF01])
[n:127.0.0.1:42079_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[falsepredicate_shard4_replica1]
[junit4] 2> 137675 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 2147483647 transient cores
[junit4] 2> 137778 INFO
(zkCallback-322-thread-1-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_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: [3])
[junit4] 2> 137778 INFO
(zkCallback-343-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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: [3])
[junit4] 2> 137778 INFO
(zkCallback-330-thread-2-processing-n:127.0.0.1:38091_solr)
[n:127.0.0.1:38091_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: [3])
[junit4] 2> 137778 INFO (zkCallback-337-thread-1) [ ]
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: [3])
[junit4] 2> 138693 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.6.1
[junit4] 2> 138731 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.s.IndexSchema
[falsepredicate_shard4_replica1] Schema name=test
[junit4] 2> 138816 WARN
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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> 138817 WARN
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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> 138818 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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> 138818 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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> 138819 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 138819 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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_22607AD7AE46FF01-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_22607AD7AE46FF01-001/tempDir-001/node2/./falsepredicate_shard4_replica1/data/]
[junit4] 2> 138849 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 138849 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_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> 138850 INFO
(coreLoadExecutor-787-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4
x:falsepredicate_shard4_replica1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 138850 INFO (coreLoadExecutor-787-thread-1-proc
[...truncated too long message...]
ocessing-n:127.0.0.1:42079_solr) [n:127.0.0.1:42079_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 188252 INFO
(zkCallback-349-thread-1-processing-n:127.0.0.1:42207_solr)
[n:127.0.0.1:42207_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 188252 INFO (coreCloseExecutor-860-thread-4)
[n:127.0.0.1:42207_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@79ed0dd2
[junit4] 2> 188252 INFO (coreCloseExecutor-858-thread-3)
[n:127.0.0.1:42609_solr c:waitforstate s:shard1 r:core_node1
x:waitforstate_shard1_replica1] o.a.s.c.SolrCore [waitforstate_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@7de7acd7
[junit4] 2> 188253 INFO (coreCloseExecutor-858-thread-1)
[n:127.0.0.1:42609_solr c:falsepredicate s:shard3 r:core_node4
x:falsepredicate_shard3_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard3.replica1
[junit4] 2> 188254 INFO (coreCloseExecutor-860-thread-1)
[n:127.0.0.1:42207_solr c:falsepredicate s:shard2 r:core_node6
x:falsepredicate_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard2.replica1
[junit4] 2> 188254 INFO (coreCloseExecutor-859-thread-1)
[n:127.0.0.1:42079_solr c:falsepredicate s:shard4 r:core_node5
x:falsepredicate_shard4_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.falsepredicate.shard4.replica1
[junit4] 2> 188254 INFO (coreCloseExecutor-858-thread-2)
[n:127.0.0.1:42609_solr c:testcollection s:shard3 r:core_node2
x:testcollection_shard3_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.testcollection.shard3.replica1
[junit4] 2> 188254 INFO (coreCloseExecutor-860-thread-2)
[n:127.0.0.1:42207_solr c:delayed s:shard1 r:core_node1
x:delayed_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric reporters
for: solr.core.delayed.shard1.replica1
[junit4] 2> 188255 INFO (coreCloseExecutor-859-thread-2)
[n:127.0.0.1:42079_solr c:testcollection s:shard2 r:core_node4
x:testcollection_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.testcollection.shard2.replica1
[junit4] 2> 188255 INFO (coreCloseExecutor-860-thread-4)
[n:127.0.0.1:42207_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> 188256 INFO (coreCloseExecutor-860-thread-3)
[n:127.0.0.1:42207_solr c:testcollection s:shard4 r:core_node1
x:testcollection_shard4_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.testcollection.shard4.replica1
[junit4] 2> 188257 INFO (coreCloseExecutor-858-thread-3)
[n:127.0.0.1:42609_solr c:waitforstate s:shard1 r:core_node1
x:waitforstate_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.waitforstate.shard1.replica1
[junit4] 2> 188258 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 0x15e017e1fee0015, 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> 188259 INFO (jetty-closer-308-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98517883061927944-127.0.0.1:42609_solr-n_0000000000) closing
[junit4] 2> 188259 INFO
(OverseerStateUpdate-98517883061927944-127.0.0.1:42609_solr-n_0000000000)
[n:127.0.0.1:42609_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:42609_solr
[junit4] 2> 188260 INFO
(zkCallback-322-thread-2-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (1)
[junit4] 2> 188260 WARN
(zkCallback-322-thread-2-processing-n:127.0.0.1:42609_solr)
[n:127.0.0.1:42609_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 188261 INFO (jetty-closer-308-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@523ec670{/solr,null,UNAVAILABLE}
[junit4] 2> 189752 WARN
(zkCallback-358-thread-1-processing-n:127.0.0.1:33773_solr)
[n:127.0.0.1:33773_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 189752 INFO (jetty-closer-308-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7f91ba2c{/solr,null,UNAVAILABLE}
[junit4] 2> 189758 WARN
(zkCallback-343-thread-1-processing-n:127.0.0.1:42079_solr)
[n:127.0.0.1:42079_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 189758 INFO (jetty-closer-308-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@779f5868{/solr,null,UNAVAILABLE}
[junit4] 2> 189758 WARN
(zkCallback-349-thread-2-processing-n:127.0.0.1:42207_solr)
[n:127.0.0.1:42207_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 189759 INFO (jetty-closer-308-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@66b474ac{/solr,null,UNAVAILABLE}
[junit4] 2> 189759 ERROR
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-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> 189760 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35253 35253
[junit4] 2> 195826 INFO (Thread-305) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35253 35253
[junit4] 2> 195827 WARN (Thread-305) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 7 /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> 8 /solr/clusterprops.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 7 /solr/collections/falsepredicate/state.json
[junit4] 2> 7 /solr/collections/testcollection/state.json
[junit4] 2> 3
/solr/overseer_elect/election/98517883061927944-127.0.0.1:42609_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> 2
/solr/overseer_elect/election/98517883061927947-127.0.0.1:40319_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections
[junit4] 2> 5 /solr/live_nodes
[junit4] 2>
[junit4] 2> 195827 INFO
(SUITE-TestCollectionStateWatchers-seed#[22607AD7AE46FF01]-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_22607AD7AE46FF01-001
[junit4] 2> NOTE: test params are:
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=2492, maxDocsPerChunk=7, blockSize=6),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=2492, blockSize=6)),
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=sr-ME,
timezone=Asia/Katmandu
[junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=218056752,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [CommonAdminParamsTest,
HyperbolicCosineEvaluatorTest, LBHttpSolrClientBuilderTest,
HttpSolrClientBuilderTest, RoundEvaluatorTest, SubtractEvaluatorTest,
CosineEvaluatorTest, ArcSineEvaluatorTest, LessThanEqualToEvaluatorTest,
TestJsonRecordReader, SolrQueryTest, EqualsEvaluatorTest, QueryResponseTest,
DocumentAnalysisResponseTest, UuidEvaluatorTest, MergeIndexesEmbeddedTest,
TestDocumentObjectBinder, SolrZkClientTest, FloorEvaluatorTest,
StreamExpressionToExplanationTest, SelectWithEvaluatorsTest,
SolrExampleStreamingBinaryTest, SolrExampleBinaryTest, SolrExampleJettyTest,
SolrExampleEmbeddedTest, LargeVolumeBinaryJettyTest, LargeVolumeEmbeddedTest,
LargeVolumeJettyTest, TestSolrProperties, TestCoreAdmin, TestHash,
ContentStreamTest, ModifiableSolrParamsTest, TestFastInputStream,
TestXMLEscaping, SolrDocumentTest, SolrExceptionTest, TestUpdateRequestCodec,
ClientUtilsTest, NamedListTest, CollectionAdminRequestRequiredParamsTest,
GetByIdTest, TestSolrJErrorHandling, BasicHttpSolrClientTest,
CloudSolrClientBuilderTest, CloudSolrClientCacheTest, CloudSolrClientTest,
GraphExpressionTest, JDBCStreamTest, TestDelegationTokenResponse,
TestCollectionStateWatchers]
[junit4] Completed [130/130 (1!)] on J1 in 64.85s, 9 tests, 1 failure <<<
FAILURES!
[...truncated 42659 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
