Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2361/
2 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest: 1)
Thread[id=134, name=qtp1320716203-134, state=TIMED_WAITING,
group=TGRP-SelectWithEvaluatorsTest] at sun.misc.Unsafe.park(Native
Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest:
1) Thread[id=134, name=qtp1320716203-134, state=TIMED_WAITING,
group=TGRP-SelectWithEvaluatorsTest]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([32AE9B573185ED25]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=134, name=qtp1320716203-134, state=TIMED_WAITING,
group=TGRP-SelectWithEvaluatorsTest] at sun.misc.Unsafe.park(Native
Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=134, name=qtp1320716203-134, state=TIMED_WAITING,
group=TGRP-SelectWithEvaluatorsTest]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([32AE9B573185ED25]:0)
Build Log:
[...truncated 15380 lines...]
[junit4] Suite:
org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/init-core-data-001
[junit4] 2> 11111 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 11112 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-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> 11112 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 11115 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001
[junit4] 2> 11143 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 11162 INFO (Thread-31) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 11162 INFO (Thread-31) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 11260 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:58591
[junit4] 2> 11324 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Exception causing close of session 0x0: ZooKeeperServer
not running
[junit4] 2> 11425 ERROR (Thread-31) [ ] 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> 13024 INFO (zkConnectionManagerCallback-10-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13075 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.Server
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash:
82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 13075 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.Server
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash:
82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 13077 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.Server
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash:
82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 13081 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.Server
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash:
82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 13082 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.session
DefaultSessionIdManager workerName=node0
[junit4] 2> 13082 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.session
No SessionScavenger set, using defaults
[junit4] 2> 13082 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.session
Scavenging every 660000ms
[junit4] 2> 13082 INFO (jetty-launcher-7-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@591dacdc{/solr,null,AVAILABLE}
[junit4] 2> 13092 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.session
DefaultSessionIdManager workerName=node0
[junit4] 2> 13092 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.session
No SessionScavenger set, using defaults
[junit4] 2> 13093 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.session
Scavenging every 600000ms
[junit4] 2> 13093 INFO (jetty-launcher-7-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@59d003e9{/solr,null,AVAILABLE}
[junit4] 2> 13094 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.session
DefaultSessionIdManager workerName=node0
[junit4] 2> 13094 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.session
No SessionScavenger set, using defaults
[junit4] 2> 13094 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.session
Scavenging every 660000ms
[junit4] 2> 13094 INFO (jetty-launcher-7-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2882a621{/solr,null,AVAILABLE}
[junit4] 2> 13095 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.session
DefaultSessionIdManager workerName=node0
[junit4] 2> 13095 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.session
No SessionScavenger set, using defaults
[junit4] 2> 13095 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.session
Scavenging every 600000ms
[junit4] 2> 13096 INFO (jetty-launcher-7-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@447766b{/solr,null,AVAILABLE}
[junit4] 2> 13112 INFO (jetty-launcher-7-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@690ccd77{SSL,[ssl,
http/1.1]}{127.0.0.1:43895}
[junit4] 2> 13113 INFO (jetty-launcher-7-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@55bb6c6b{SSL,[ssl,
http/1.1]}{127.0.0.1:47372}
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.Server
Started @15405ms
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.Server
Started @15405ms
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43895}
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47372}
[junit4] 2> 13116 ERROR (jetty-launcher-7-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 13116 ERROR (jetty-launcher-7-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 13116 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 13117 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T00:24:42.852Z
[junit4] 2> 13117 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T00:24:42.853Z
[junit4] 2> 13117 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 13117 INFO (jetty-launcher-7-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@656e1dec{SSL,[ssl,
http/1.1]}{127.0.0.1:46199}
[junit4] 2> 13117 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.Server
Started @15406ms
[junit4] 2> 13117 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46199}
[junit4] 2> 13118 ERROR (jetty-launcher-7-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 13118 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 13118 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13118 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 13118 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T00:24:42.854Z
[junit4] 2> 13119 INFO (jetty-launcher-7-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1a8b69d{SSL,[ssl,
http/1.1]}{127.0.0.1:40494}
[junit4] 2> 13119 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.Server
Started @15408ms
[junit4] 2> 13119 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40494}
[junit4] 2> 13119 ERROR (jetty-launcher-7-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 13120 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 13120 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13120 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 13120 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T00:24:42.856Z
[junit4] 2> 13127 INFO (zkConnectionManagerCallback-12-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13136 INFO (zkConnectionManagerCallback-14-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13136 INFO (zkConnectionManagerCallback-16-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13136 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13137 INFO (zkConnectionManagerCallback-18-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13140 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13140 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13141 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13169 INFO (jetty-launcher-7-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58591/solr
[junit4] 2> 13174 INFO (jetty-launcher-7-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58591/solr
[junit4] 2> 13179 INFO (jetty-launcher-7-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58591/solr
[junit4] 2> 13184 INFO (jetty-launcher-7-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58591/solr
[junit4] 2> 13194 INFO (zkConnectionManagerCallback-34-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13199 INFO (zkConnectionManagerCallback-31-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13200 INFO (zkConnectionManagerCallback-28-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13200 INFO (zkConnectionManagerCallback-32-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13209 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1049018699f0005, likely client has closed socket
[junit4] 2> 13211 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1049018699f0008, likely client has closed socket
[junit4] 2> 13217 INFO
(zkConnectionManagerCallback-37-thread-1-processing-n:127.0.0.1:47372_solr)
[n:127.0.0.1:47372_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13224 INFO
(zkConnectionManagerCallback-38-thread-1-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13229 INFO
(zkConnectionManagerCallback-40-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13244 INFO
(zkConnectionManagerCallback-42-thread-1-processing-n:127.0.0.1:43895_solr)
[n:127.0.0.1:43895_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 13326 WARN (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 13326 WARN (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 13327 WARN (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 13329 WARN (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.c.ZkController The _default configset could
not be uploaded. Please provide 'solr.default.confdir' parameter that points to
a configset intended to be the default. Current 'solr.default.confdir' value:
null
[junit4] 2> 13357 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 13357 INFO (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 13358 INFO (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 13359 INFO (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 13360 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:47372_solr
[junit4] 2> 13363 INFO (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43895_solr
[junit4] 2> 13363 INFO (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40494_solr
[junit4] 2> 13364 INFO (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46199_solr
[junit4] 2> 13381 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.c.Overseer Overseer
(id=73341928470413321-127.0.0.1:47372_solr-n_0000000000) starting
[junit4] 2> 13421 INFO
(zkCallback-41-thread-1-processing-n:127.0.0.1:43895_solr)
[n:127.0.0.1:43895_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 13424 INFO
(zkCallback-36-thread-1-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 13455 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 13456 INFO
(zkCallback-35-thread-1-processing-n:127.0.0.1:47372_solr)
[n:127.0.0.1:47372_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 13638 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47372_solr
[junit4] 2> 13640 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 13640 INFO
(zkCallback-36-thread-1-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 13649 INFO
(zkCallback-41-thread-1-processing-n:127.0.0.1:43895_solr)
[n:127.0.0.1:43895_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 13649 INFO
(zkCallback-35-thread-1-processing-n:127.0.0.1:47372_solr)
[n:127.0.0.1:47372_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 13818 INFO (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43895.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13842 INFO (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46199.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13900 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47372.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13952 INFO (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46199.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13952 INFO (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46199.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13964 INFO (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43895.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13964 INFO (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43895.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13979 INFO (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40494.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13984 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47372.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 13984 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47372.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 14002 INFO (jetty-launcher-7-thread-1)
[n:127.0.0.1:47372_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node1/.
[junit4] 2> 14010 INFO (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40494.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 14011 INFO (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40494.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 14019 INFO (jetty-launcher-7-thread-3)
[n:127.0.0.1:43895_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node3/.
[junit4] 2> 14042 INFO (jetty-launcher-7-thread-4)
[n:127.0.0.1:40494_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node4/.
[junit4] 2> 14042 INFO (jetty-launcher-7-thread-2)
[n:127.0.0.1:46199_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node2/.
[junit4] 2> 14437 INFO (zkConnectionManagerCallback-52-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 14452 INFO (zkConnectionManagerCallback-56-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 14454 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 14455 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58591/solr ready
[junit4] 2> 14723 INFO (qtp1305078931-140) [n:127.0.0.1:47372_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 14772 INFO
(OverseerThreadFactory-60-thread-1-processing-n:127.0.0.1:47372_solr)
[n:127.0.0.1:47372_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
collection1_collection
[junit4] 2> 14923 INFO
(OverseerStateUpdate-73341928470413321-127.0.0.1:47372_solr-n_0000000000)
[n:127.0.0.1:47372_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46199/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 14928 INFO
(OverseerStateUpdate-73341928470413321-127.0.0.1:47372_solr-n_0000000000)
[n:127.0.0.1:47372_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_collection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40494/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 15258 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 15259 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 15263 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 15263 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 15400 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 15400 INFO
(zkCallback-36-thread-1-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 15415 INFO
(zkCallback-39-thread-2-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 15415 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16313 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.0.0
[junit4] 2> 16318 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.0.0
[junit4] 2> 16334 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema
[collection1_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 16357 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema
[collection1_collection_shard2_replica_n2] Schema name=test
[junit4] 2> 16485 WARN (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr
loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 16489 WARN (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr
loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 16502 WARN (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Field
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 16502 WARN (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Field text is
not multivalued and destination for multiple copyFields (3)
[junit4] 2> 16510 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
test/1.6 with uniqueid field id
[junit4] 2> 16513 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'collection1_collection_shard1_replica_n1' using configuration from
collection collection1_collection, trusted=true
[junit4] 2> 16515 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_46199.solr.core.collection1_collection.shard1.replica_n1'
(registry 'solr.core.collection1_collection.shard1.replica_n1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 16515 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 16515 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore
[[collection1_collection_shard1_replica_n1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node2/collection1_collection_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node2/./collection1_collection_shard1_replica_n1/data/]
[junit4] 2> 16522 WARN (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema Field
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 16522 WARN (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema Field text is
not multivalued and destination for multiple copyFields (3)
[junit4] 2> 16523 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema
test/1.6 with uniqueid field id
[junit4] 2> 16523 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.CoreContainer Creating
SolrCore 'collection1_collection_shard2_replica_n2' using configuration from
collection collection1_collection, trusted=true
[junit4] 2> 16523 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_40494.solr.core.collection1_collection.shard2.replica_n2'
(registry 'solr.core.collection1_collection.shard2.replica_n2') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@75433683
[junit4] 2> 16523 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 16523 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore
[[collection1_collection_shard2_replica_n2] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node4/collection1_collection_shard2_replica_n2],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.SelectWithEvaluatorsTest_32AE9B573185ED25-001/tempDir-001/node4/./collection1_collection_shard2_replica_n2/data/]
[junit4] 2> 16592 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 16592 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 16593 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 16593 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 16597 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@5a48b104[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 16600 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 16600 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 16601 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 16601 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 16602 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 16605 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 16605 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 16605 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1593149212685500416
[junit4] 2> 16607 INFO
(searcherExecutor-68-thread-1-processing-n:127.0.0.1:46199_solr
x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection
r:core_node3) [n:127.0.0.1:46199_solr c:collection1_collection s:shard1
r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_collection_shard1_replica_n1] Registered new searcher
Searcher@5a48b104[collection1_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 16608 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@3c6dec8c[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 16610 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 16615 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 16615 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 16623 INFO
(searcherExecutor-69-thread-1-processing-n:127.0.0.1:40494_solr
x:collection1_collection_shard2_replica_n2 s:shard2 c:collection1_collection
r:core_node4) [n:127.0.0.1:40494_solr c:collection1_collection s:shard2
r:core_node4 x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore
[collection1_collection_shard2_replica_n2] Registered new searcher
Searcher@3c6dec8c[collection1_collection_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 16624 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1593149212705423360
[junit4] 2> 16648 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 16649 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 16650 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:40494/solr/collection1_collection_shard2_replica_n2/
[junit4] 2> 16651 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 16651 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SyncStrategy
https://127.0.0.1:40494/solr/collection1_collection_shard2_replica_n2/ has no
replicas
[junit4] 2> 16651 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 16648 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 16656 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 16656 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:46199/solr/collection1_collection_shard1_replica_n1/
[junit4] 2> 16656 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 16656 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy
https://127.0.0.1:46199/solr/collection1_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 16656 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 16665 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
https://127.0.0.1:46199/solr/collection1_collection_shard1_replica_n1/ shard1
[junit4] 2> 16665 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
https://127.0.0.1:40494/solr/collection1_collection_shard2_replica_n2/ shard2
[junit4] 2> 16768 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16768 INFO
(zkCallback-39-thread-2-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16768 INFO
(zkCallback-36-thread-1-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16768 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16817 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 16817 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 16823 INFO (qtp1320716203-134) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1566
[junit4] 2> 16824 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1561
[junit4] 2> 16842 INFO (qtp1305078931-140) [n:127.0.0.1:47372_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 16923 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16923 INFO
(zkCallback-39-thread-2-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16923 INFO
(zkCallback-36-thread-1-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16923 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1_collection/state.json] for collection
[collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17844 INFO (qtp1305078931-140) [n:127.0.0.1:47372_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=3120
[junit4] 2> 17851 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1_collection failOnTimeout:true timeout (sec):90
[junit4] 2> 17852 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection:
collection1_collection
[junit4] 2> 17859 INFO (qtp1305078931-142) [n:127.0.0.1:47372_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params
collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 17876 INFO
(OverseerCollectionConfigSetProcessor-73341928470413321-127.0.0.1:47372_solr-n_0000000000)
[n:127.0.0.1:47372_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> 18003 INFO (qtp1305078931-142) [n:127.0.0.1:47372_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2}
status=0 QTime=144
[junit4] 2> 18052 INFO
(TEST-SelectWithEvaluatorsTest.testSelectWithEvaluatorsStream-seed#[32AE9B573185ED25])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSelectWithEvaluatorsStream
[junit4] 2> 18197 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard1_replica_n1] webapp=/solr path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:40494/solr/collection1_collection_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1593149214353784832)} 0 1
[junit4] 2> 18237 INFO (qtp833128252-128) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1593149214396776448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18237 INFO (qtp833128252-128) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 18238 INFO (qtp833128252-128) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 18248 INFO (qtp833128252-128) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard1_replica_n1] webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40494/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 11
[junit4] 2> 18267 INFO (qtp1320716203-135) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1593149214428233728,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18267 INFO (qtp1320716203-135) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 18272 INFO (qtp1320716203-135) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 18272 INFO (qtp1320716203-135) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard2_replica_n2] webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40494/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 5
[junit4] 2> 18273 INFO (qtp1320716203-137) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard2_replica_n2] webapp=/solr path=/update
params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1593149214261510144),commit=} 0 182
[junit4] 2> 18366 INFO (qtp833128252-127) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard1_replica_n1] webapp=/solr path=/update
params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[1
(1593149214481711104)]} 0 49
[junit4] 2> 18408 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1593149214576082944,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18412 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ac011f1
commitCommandVersion:1593149214576082944
[junit4] 2> 18421 INFO (qtp1320716203-137) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1593149214589714432,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18425 INFO (qtp1320716203-137) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 18425 INFO (qtp1320716203-137) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 18426 INFO (qtp1320716203-137) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard2_replica_n2] webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46199/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 5
[junit4] 2> 18459 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@29b0233e[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 18460 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 18462 INFO
(searcherExecutor-68-thread-1-processing-n:127.0.0.1:46199_solr
x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection
r:core_node3) [n:127.0.0.1:46199_solr c:collection1_collection s:shard1
r:core_node3 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_collection_shard1_replica_n1] Registered new searcher
Searcher@29b0233e[collection1_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
[junit4] 2> 18462 INFO (qtp833128252-125) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard1_replica_n1] webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46199/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 54
[junit4] 2> 18469 INFO (qtp833128252-127) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory
[collection1_collection_shard1_replica_n1] webapp=/solr path=/update
params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=}
0 98
[junit4] 2> 18495 INFO (zkConnectionManagerCallback-60-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18506 INFO
(TEST-SelectWithEvaluatorsTest.testSelectWithEvaluatorsStream-seed#[32AE9B573185ED25])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 18507 INFO
(TEST-SelectWithEvaluatorsTest.testSelectWithEvaluatorsStream-seed#[32AE9B573185ED25])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:58591/solr ready
[junit4] 2> 18584 INFO (qtp1320716203-138) [n:127.0.0.1:40494_solr
c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.S.Request
[collection1_collection_shard2_replica_n2] webapp=/solr path=/select
params={q=*:*&distrib=false&fl=id,a_s,b_i,c_d,d_b&sort=id+asc&wt=json&version=2.2}
hits=0 status=0 QTime=12
[junit4] 2> 18608 INFO (qtp833128252-128) [n:127.0.0.1:46199_solr
c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request
[collection1_collection_shard1_replica_n1] webapp=/solr path=/select
params={q=*:*&distrib=false&fl=id,a_s,b_i,c_d,d_b&sort=id+asc&wt=json&version=2.2}
hits=1 status=0 QTime=36
[junit4] 2> 18618 INFO
(TEST-SelectWithEvaluatorsTest.testSelectWithEvaluatorsStream-seed#[32AE9B573185ED25])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSelectWithEvaluatorsStream
[junit4] 2> 18704 INFO (jetty-closer-8-thread-4) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@656e1dec{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 18704 INFO (jetty-closer-8-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@55bb6c6b{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 18708 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=373308328
[junit4] 2> 18709 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=594140042
[junit4] 2> 18719 INFO (jetty-closer-8-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@690ccd77{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 18719 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 18736 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 18736 INFO (jetty-closer-8-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@1a8b69d{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 18755 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=703365673
[junit4] 2> 18755 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@188327ad: rootName =
solr_47372, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@12e76644
[junit4] 2> 18755 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 18756 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@50f06e67: rootName =
solr_46199, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@befb435
[junit4] 2> 18762 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@222fb2f9: rootName =
solr_43895, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@5160a373
[junit4] 2> 18762 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=759804559
[junit4] 2> 18763 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 18786 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 18786 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2d744eb4: rootName =
solr_46199, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5285b38
[junit4] 2> 18793 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 18793 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2b5a0697: rootName =
solr_47372, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5285b38
[junit4] 2> 18798 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 18798 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1f88cc01: rootName =
solr_43895, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5285b38
[junit4] 2> 18820 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 18820 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1a657322: rootName =
solr_43895, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5fa7472f
[junit4] 2> 18822 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 18822 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@24808fac: rootName =
solr_46199, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5fa7472f
[junit4] 2> 18824 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 18824 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@71507c02: rootName =
solr_47372, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5fa7472f
[junit4] 2> 18824 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5fb0a51: rootName =
solr_40494, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@79904280
[junit4] 2> 18826 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:43895_solr
[junit4] 2> 18828 INFO
(zkCallback-39-thread-2-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 18828 INFO
(zkCallback-35-thread-1-processing-n:127.0.0.1:47372_solr)
[n:127.0.0.1:47372_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 18828 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 18828 INFO (jetty-closer-8-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 18830 INFO
(zkCallback-41-thread-1-processing-n:127.0.0.1:43895_solr)
[n:127.0.0.1:43895_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 18831 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 18831 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6dcce2ef: rootName =
solr_40494, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5285b38
[junit4] 2> 18835 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 18835 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5741cae7: rootName =
solr_40494, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5fa7472f
[junit4] 2> 18842 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 18847 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:46199_solr
[junit4] 2> 18848 INFO (jetty-closer-8-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 18848 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 18855 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:47372_solr
[junit4] 2> 18855 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 18856 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 18856 INFO (jetty-closer-8-thread-2) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 18857 INFO
(zkCallback-39-thread-2-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 18857 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 18860 INFO (jetty-closer-8-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@591dacdc{/solr,null,UNAVAILABLE}
[junit4] 2> 18867 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:40494_solr
[junit4] 2> 18868 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 18869 INFO
(zkCallback-39-thread-2-processing-n:127.0.0.1:40494_solr)
[n:127.0.0.1:40494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 18869 INFO (jetty-closer-8-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 18904 INFO (jetty-closer-8-thread-1) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 18912 INFO (coreCloseExecutor-80-thread-1)
[n:127.0.0.1:46199_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_collection_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@12b22195
[junit4] 2> 18912 INFO
(zkCallback-35-thread-2-processing-n:127.0.0.1:47372_solr)
[n:127.0.0.1:47372_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (0)
[junit4] 2> 18913 INFO (coreCloseExecutor-80-thread-1)
[n:127.0.0.1:46199_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.collection1_collection.shard1.replica_n1, tag=313663893
[junit4] 2> 18916 INFO (coreCloseExecutor-80-thread-1)
[n:127.0.0.1:46199_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d2c0343: rootName =
solr_46199, domain = solr.core.collection1_collection.shard1.replica_n1,
service url = null, agent id = null] for registry
solr.core.collection1_collection.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@29d43924
[junit4] 2> 18923 INFO (coreCloseExecutor-81-thread-1)
[n:127.0.0.1:40494_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore
[collection1_collection_shard2_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@35babd4c
[junit4] 2> 18923 INFO (coreCloseExecutor-81-thread-1)
[n:127.0.0.1:40494_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.collection1_collection.shard2.replica_n2, tag=901430604
[junit4] 2> 18923 INFO (coreCloseExecutor-81-thread-1)
[n:127.0.0.1:40494_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@74e25f4f: rootName
= solr_40494, domain = solr.core.collection1_collection.shard2.replica_n2,
service url = null, agent id = null] for registry
solr.core.collection1_collection.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@15b888c7
[junit4] 2> 18936 INFO (jetty-closer-8-thread-2) [ ] o.a.s.c.Overseer
Overseer (id=73341928470413321-127.0.0.1:47372_solr-n_0000000000) closing
[junit4] 2> 18937 INFO
(OverseerStateUpdate-73341928470413321-127.0.0.1:47372_solr-n_0000000000)
[n:127.0.0.1:47372_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:47372_solr
[junit4] 2> 18941 WARN
(OverseerAutoScalingTriggerThread-73341928470413321-127.0.0.1:47372_solr-n_0000000000)
[n:127.0.0.1:47372_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 18957 INFO (jetty-closer-8-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@447766b{/solr,null,UNAVAILABLE}
[junit4] 2> 18954 INFO
(zkCallback-36-thread-2-processing-n:127.0.0.1:46199_solr)
[n:127.0.0.1:46199_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:46199_solr
[junit4] 2> 18959 INFO (jetty-closer-8-thread-2) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 18987 INFO (coreCloseExecutor-81-thread-1)
[n:127.0.0.1:40494_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.collection1_collection.shard2.leader, tag=901430604
[junit4] 2> 18990 INFO (coreCloseExecutor-80-thread-1)
[n:127.0.0.1:46199_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.collection1_collection.shard1.leader, tag=313663893
[junit4] 2> 19023 INFO (jetty-closer-8-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2882a621{/solr,null,UNAVAILABLE}
[junit4] 2> 19026 INFO (jetty-closer-8-thread-3) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 19032 INFO (jetty-closer-8-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@59d003e9{/solr,null,UNAVAILABLE}
[junit4] 2> 19032 INFO (jetty-closer-8-thread-4) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 49026 WARN (jetty-closer-8-thread-3) [ ]
o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp1320716203{STOPPING,8<=8<=10000,i=0,q=1} Couldn't stop
Thread[qtp1320716203-134,5,TGRP-SelectWithEvaluatorsTest]
[junit4] 2> 49027 ERROR
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-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> 49028 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:58591 58591
[junit4] 2> 49229 INFO (Thread-31) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:58591 58591
[junit4] 2> 49230 WARN (Thread-31) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2> 2
/solr/collections/collection1_collection/terms/shard1
[junit4] 2> 2
/solr/collections/collection1_collection/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 10
/solr/collections/collection1_collection/state.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 2
/solr/overseer_elect/election/73341928470413322-127.0.0.1:46199_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2>
[junit4] 2> 49240 INFO
(SUITE-SelectWithEvaluatorsTest-seed#[32AE9B573185ED25]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> Feb 23, 2018 12:25:18 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> Feb 23, 2018 12:25:39 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest:
[junit4] 2> 1) Thread[id=134, name=qtp1320716203-134,
state=TIMED_WAITING, group=TGRP-SelectWithEvaluatorsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Feb 23, 2018 12:25:39 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=134, name=qtp1320716203-134,
state=TIMED_WAITING, group=TGRP-SelectWithEvaluatorsTest]
[junit4] 2> Feb 23, 2018 12:25:42 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=134, name=qtp1320716203-134,
state=TIMED_WAITING, group=TGRP-SelectWithEvaluatorsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2df58717),
locale=mk, timezone=Asia/Kuching
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=2,free=204712360,total=307232768
[junit4] 2> NOTE: All tests run in this JVM: [LargeVolumeJettyTest,
NormalizeEvaluatorTest, AddEvaluatorTest, ExclusiveOrEvaluatorTest,
GetByIdTest, TestBatchUpdate, MultiplyEvaluatorTest, TestValidatingJsonMap,
SelectWithEvaluatorsTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=SelectWithEvaluatorsTest -Dtests.seed=32AE9B573185ED25
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=mk
-Dtests.timezone=Asia/Kuching -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | SelectWithEvaluatorsTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.client.solrj.io.stream.SelectWithEvaluatorsTest:
[junit4] > 1) Thread[id=134, name=qtp1320716203-134,
state=TIMED_WAITING, group=TGRP-SelectWithEvaluatorsTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([32AE9B573185ED25]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=134, name=qtp1320716203-134,
state=TIMED_WAITING, group=TGRP-SelectWithEvaluatorsTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([32AE9B573185ED25]:0)
[junit4] Completed [79/146 (1!)] on J2 in 61.25s, 1 test, 2 errors <<<
FAILURES!
[...truncated 49328 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
