Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/850/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest: 1) Thread[id=26952,
name=nioEventLoopGroup-13-2, state=TIMED_WAITING,
group=TGRP-HDFSCollectionsAPITest] at java.lang.Thread.sleep(Native
Method) at
io.netty.util.concurrent.SingleThreadEventExecutor.confirmShutdown(SingleThreadEventExecutor.java:790)
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:525)
at
io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
at
io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
at
io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest:
1) Thread[id=26952, name=nioEventLoopGroup-13-2, state=TIMED_WAITING,
group=TGRP-HDFSCollectionsAPITest]
at java.lang.Thread.sleep(Native Method)
at
io.netty.util.concurrent.SingleThreadEventExecutor.confirmShutdown(SingleThreadEventExecutor.java:790)
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:525)
at
io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
at
io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
at
io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([CF4178A5A35718A6]:0)
Build Log:
[...truncated 15369 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest
[junit4] 2> 1769850 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to
test-framework derived value of
'/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1769850 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1769851 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/data-dir-148-001
[junit4] 2> 1769851 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1769853 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1769853 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/tempDir-001
[junit4] 2> 1769853 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1769854 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1769854 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1769960 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:55273
[junit4] 2> 1769960 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:55273
[junit4] 2> 1769960 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55273
[junit4] 2> 1769960 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 55273
[junit4] 2> 1769963 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1769983 INFO (zkConnectionManagerCallback-8717-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1769983 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1769992 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1769999 INFO (zkConnectionManagerCallback-8719-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1769999 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770001 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1770006 INFO (zkConnectionManagerCallback-8721-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770006 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770115 WARN (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 1770115 WARN (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 1770115 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1770115 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1770115 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1770116 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1770116 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1770116 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1770118 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1770118 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1770118 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1770119 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@44b3541{/solr,null,AVAILABLE}
[junit4] 2> 1770123 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@4b973cda{HTTP/1.1, (http/1.1,
h2c)}{127.0.0.1:55278}
[junit4] 2> 1770123 INFO (jetty-launcher-8722-thread-1) [ ]
o.e.j.s.Server Started @1770209ms
[junit4] 2> 1770123 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55278}
[junit4] 2> 1770123 ERROR (jetty-launcher-8722-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1770123 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1770123 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.6.0
[junit4] 2> 1770123 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1770124 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1770124 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-04-11T16:48:56.640Z
[junit4] 2> 1770124 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1770124 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1770124 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1770125 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1770126 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3a17d94d{/solr,null,AVAILABLE}
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5ea743e4{HTTP/1.1, (http/1.1,
h2c)}{127.0.0.1:55279}
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.e.j.s.Server Started @1770214ms
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55279}
[junit4] 2> 1770128 ERROR (jetty-launcher-8722-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.6.0
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1770128 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1770129 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-04-11T16:48:56.645Z
[junit4] 2> 1770130 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1770131 INFO (zkConnectionManagerCallback-8724-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770131 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770133 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1770141 INFO (zkConnectionManagerCallback-8726-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770141 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770144 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1770296 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1770297 WARN (jetty-launcher-8722-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@7186f592[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770297 WARN (jetty-launcher-8722-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@7186f592[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770304 WARN (jetty-launcher-8722-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@60aa00d4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770304 WARN (jetty-launcher-8722-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@60aa00d4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770306 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55273/solr
[junit4] 2> 1770307 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1770310 INFO (zkConnectionManagerCallback-8733-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770311 INFO (jetty-launcher-8722-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770418 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1770429 INFO (zkConnectionManagerCallback-8736-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770429 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 1770431 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1770432 WARN (jetty-launcher-8722-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@efe2f54[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770432 WARN (jetty-launcher-8722-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@efe2f54[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770439 WARN (jetty-launcher-8722-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@1fe4355b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770439 WARN (jetty-launcher-8722-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@1fe4355b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1770441 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55273/solr
[junit4] 2> 1770442 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1770447 INFO (zkConnectionManagerCallback-8742-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770447 INFO (jetty-launcher-8722-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770554 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1770561 INFO (zkConnectionManagerCallback-8746-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770561 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 1770608 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:55279_solr
[junit4] 2> 1770610 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.Overseer Overseer
(id=72143612371664904-127.0.0.1:55279_solr-n_0000000000) starting
[junit4] 2> 1770636 INFO
(OverseerStateUpdate-72143612371664904-127.0.0.1:55279_solr-n_0000000000)
[n:127.0.0.1:55279_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:55279_solr
[junit4] 2> 1770637 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55279_solr
[junit4] 2> 1770643 INFO
(OverseerStateUpdate-72143612371664904-127.0.0.1:55279_solr-n_0000000000)
[n:127.0.0.1:55279_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1770653 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 1770654 WARN (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 1770708 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1770765 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1770774 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:55278_solr as DOWN
[junit4] 2> 1770776 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1770776 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1770776 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55278_solr
[junit4] 2> 1770779 INFO (zkCallback-8745-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1770779 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1770785 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 1770785 WARN (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 1770788 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1770788 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1770790 INFO (jetty-launcher-8722-thread-2)
[n:127.0.0.1:55279_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/tempDir-001/node2/.
[junit4] 2> 1770832 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1770860 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55278.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1770868 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55278.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1770868 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55278.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1770869 INFO (jetty-launcher-8722-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/tempDir-001/node1/.
[junit4] 2> 1770885 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1770886 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1770889 INFO (zkConnectionManagerCallback-8757-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1770889 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1770892 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1770894 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55273/solr ready
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 1770938 WARN
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 1770940 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1770942 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1770942 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1770942 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1770942 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7d26d8b8{static,/static,jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 1771051 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.w.WebAppContext@1d9bb71f{hdfs,/,file:///Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/jetty-localhost-55290-hadoop-hdfs-3_2_0-tests_jar-_-any-1339142624467395168.dir/webapp/,AVAILABLE}{jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/hdfs}
[junit4] 2> 1771055 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@57f453d8{HTTP/1.1,
(http/1.1)}{localhost:55290}
[junit4] 2> 1771055 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.Server Started @1771141ms
[junit4] 2> 1771136 WARN
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 1771137 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1771139 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1771139 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1771139 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1771139 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@54b2e340{static,/static,jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 1771300 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.w.WebAppContext@641d128d{datanode,/,file:///Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/jetty-localhost-55294-hadoop-hdfs-3_2_0-tests_jar-_-any-2968437750959358344.dir/webapp/,AVAILABLE}{jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/datanode}
[junit4] 2> 1771300 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@34972ed3{HTTP/1.1,
(http/1.1)}{localhost:55294}
[junit4] 2> 1771301 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.Server Started @1771387ms
[junit4] 2> 1771343 WARN
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 1771346 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1771347 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1771347 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1771347 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1771348 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@12b03e74{static,/static,jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 1771461 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0xbee27368370eb888: Processing first
storage report for DS-bbe099e8-de08-494d-81da-7ac1a28b3f47 from datanode
a9abe3cd-db3e-4dfc-819c-c4f96a90998d
[junit4] 2> 1771461 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0xbee27368370eb888: from storage
DS-bbe099e8-de08-494d-81da-7ac1a28b3f47 node
DatanodeRegistration(127.0.0.1:55293,
datanodeUuid=a9abe3cd-db3e-4dfc-819c-c4f96a90998d, infoPort=55295,
infoSecurePort=0, ipcPort=55296,
storageInfo=lv=-57;cid=testClusterID;nsid=838889126;c=1586623737430), blocks:
0, hasStaleStorage: true, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 1771462 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0xbee27368370eb888: Processing first
storage report for DS-ffe374a7-4cda-4bed-a4b5-10b6e9482b40 from datanode
a9abe3cd-db3e-4dfc-819c-c4f96a90998d
[junit4] 2> 1771462 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0xbee27368370eb888: from storage
DS-ffe374a7-4cda-4bed-a4b5-10b6e9482b40 node
DatanodeRegistration(127.0.0.1:55293,
datanodeUuid=a9abe3cd-db3e-4dfc-819c-c4f96a90998d, infoPort=55295,
infoSecurePort=0, ipcPort=55296,
storageInfo=lv=-57;cid=testClusterID;nsid=838889126;c=1586623737430), blocks:
0, hasStaleStorage: false, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 1771556 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.w.WebAppContext@7c6d5945{datanode,/,file:///Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/jetty-localhost-55299-hadoop-hdfs-3_2_0-tests_jar-_-any-6255458419716257569.dir/webapp/,AVAILABLE}{jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/datanode}
[junit4] 2> 1771557 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@7748a7f9{HTTP/1.1,
(http/1.1)}{localhost:55299}
[junit4] 2> 1771557 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.Server Started @1771643ms
[junit4] 2> 1771674 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0x57d0051a06626be8: Processing first
storage report for DS-3fd47a0a-1152-4d48-9284-2778656814df from datanode
5812c4bd-f456-499e-9bb1-2d57de1c4fa4
[junit4] 2> 1771675 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0x57d0051a06626be8: from storage
DS-3fd47a0a-1152-4d48-9284-2778656814df node
DatanodeRegistration(127.0.0.1:55298,
datanodeUuid=5812c4bd-f456-499e-9bb1-2d57de1c4fa4, infoPort=55300,
infoSecurePort=0, ipcPort=55301,
storageInfo=lv=-57;cid=testClusterID;nsid=838889126;c=1586623737430), blocks:
0, hasStaleStorage: true, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 1771675 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0x57d0051a06626be8: Processing first
storage report for DS-57035933-55b8-40f1-bbf7-7869e9e8e6a2 from datanode
5812c4bd-f456-499e-9bb1-2d57de1c4fa4
[junit4] 2> 1771675 INFO (Block report processor) [ ]
BlockStateChange BLOCK* processReport 0x57d0051a06626be8: from storage
DS-57035933-55b8-40f1-bbf7-7869e9e8e6a2 node
DatanodeRegistration(127.0.0.1:55298,
datanodeUuid=5812c4bd-f456-499e-9bb1-2d57de1c4fa4, infoPort=55300,
infoSecurePort=0, ipcPort=55301,
storageInfo=lv=-57;cid=testClusterID;nsid=838889126;c=1586623737430), blocks:
0, hasStaleStorage: false, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 1771920 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.SolrTestCaseJ4 ###Starting testDataDirIsNotReused
[junit4] 2> 1771922 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:55279_solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1771932 INFO
(OverseerThreadFactory-7147-thread-1-processing-n:127.0.0.1:55279_solr)
[n:127.0.0.1:55279_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
test
[junit4] 2> 1772060 INFO
(OverseerStateUpdate-72143612371664904-127.0.0.1:55279_solr-n_0000000000)
[n:127.0.0.1:55279_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55279/solr";,
[junit4] 2> "node_name":"127.0.0.1:55279_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1772285 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
x:test_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1772287 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
x:test_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1772317 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1772327 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.s.IndexSchema
Schema name=minimal
[junit4] 2> 1772333 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1772333 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.CoreContainer
Creating SolrCore 'test_shard1_replica_n1' using configuration from configset
conf1, trusted=true
[junit4] 2> 1772334 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.core.test.shard1.replica_n1' (registry
'solr.core.test.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1772388 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:55292/solr_hdfs_home
[junit4] 2> 1772388 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 1772388 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SolrCore
[[test_shard1_replica_n1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/tempDir-001/node2/./test_shard1_replica_n1],
dataDir=[hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data/]
[junit4] 2> 1772389 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data/snapshot_metadata
[junit4] 2> 1772408 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 1772408 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[33554432] will allocate [1] slabs and use ~[33554432] bytes
[junit4] 2> 1772411 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 1772416 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data
[junit4] 2> 1772435 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data/index
[junit4] 2> 1772440 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 1772440 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[33554432] will allocate [1] slabs and use ~[33554432] bytes
[junit4] 2> 1772444 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 1772564 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 1772564 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1772564 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=3
[junit4] 2> 1772571 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1772571 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1772590 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@448f384a[test_shard1_replica_n1]
main]
[junit4] 2> 1772593 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1772593 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1772594 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1772594 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1663695573861007360
[junit4] 2> 1772597 INFO
(searcherExecutor-7156-thread-1-processing-n:127.0.0.1:55279_solr
x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SolrCore
[test_shard1_replica_n1] Registered new searcher
Searcher@448f384a[test_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1772604 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/test/terms/shard1 to
Terms{values={core_node2=0}, version=0}
[junit4] 2> 1772604 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/test/leaders/shard1
[junit4] 2> 1772612 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1772612 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1772612 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:55279/solr/test_shard1_replica_n1/
[junit4] 2> 1772612 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1772613 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SyncStrategy
http://127.0.0.1:55279/solr/test_shard1_replica_n1/ has no replicas
[junit4] 2> 1772613 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/test/leaders/shard1/leader after winning as
/collections/test/leader_elect/shard1/election/72143612371664904-core_node2-n_0000000000
[junit4] 2> 1772617 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:55279/solr/test_shard1_replica_n1/ shard1
[junit4] 2> 1772728 INFO (zkCallback-8745-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1772729 INFO (zkCallback-8745-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1772731 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1772736 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=452
[junit4] 2> 1772740 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at
most 45 seconds. Check all shard replicas
[junit4] 2> 1772848 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1772848 INFO (zkCallback-8745-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1772848 INFO (zkCallback-8745-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1772848 INFO (zkCallback-8745-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1772850 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:55279_solr&wt=javabin&version=2}
status=0 QTime=927
[junit4] 2> 1772850 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase waitForState (test):
[junit4] 2> 1772851 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1772852 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1772852 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1772868 INFO (qtp1348511640-26366) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/test/terms/shard1 to
Terms{values={core_node2=1}, version=1}
[junit4] 2> 1772881 INFO (qtp1348511640-26366) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.u.p.LogUpdateProcessorFactory [test_shard1_replica_n1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[1 (1663695574135734272)]} 0 26
[junit4] 2> 1772915 INFO (qtp1348511640-26363) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.u.p.LogUpdateProcessorFactory [test_shard1_replica_n1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[2 (1663695574164045824)]} 0 32
[junit4] 2> 1772916 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1663695574198648832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1772916 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@55af3fcf
commitCommandVersion:1663695574198648832
[junit4] 2> 1773407 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4aa74392[test_shard1_replica_n1]
main]
[junit4] 2> 1773409 INFO
(searcherExecutor-7156-thread-1-processing-n:127.0.0.1:55279_solr
x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ] o.a.s.c.SolrCore
[test_shard1_replica_n1] Registered new searcher
Searcher@4aa74392[test_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.6.0):C2:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201,
java.vm.version=25.201-b09, lucene.version=8.6.0, os.arch=x86_64,
java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.14.6,
timestamp=1586623739562}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1773417 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1773417 INFO (qtp1348511640-26364) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.u.p.LogUpdateProcessorFactory [test_shard1_replica_n1] webapp=/solr
path=/update
params={_stateVer_=test:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 501
[junit4] 2> 1773436 INFO (qtp1348511640-26365) [n:127.0.0.1:55279_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1 ]
o.a.s.u.p.LogUpdateProcessorFactory [test_shard1_replica_n1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[3 (1663695574726082560)]} 0 17
[junit4] 2> 1773436 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=946698017
[junit4] 2> 1773437 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55279_solr
[junit4] 2> 1773437 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1773437 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.ZkController Publish node=127.0.0.1:55279_solr as DOWN
[junit4] 2> 1773441 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.c.SolrCore [test_shard1_replica_n1]
CLOSING SolrCore org.apache.solr.core.SolrCore@42a842a2
[junit4] 2> 1773441 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.test.shard1.replica_n1, tag=SolrCore@42a842a2
[junit4] 2> 1773441 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6b4a0ff: rootName =
solr_55279, domain = solr.core.test.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.test.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@d0b3a96
[junit4] 2> 1773451 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.test.shard1.leader, tag=SolrCore@42a842a2
[junit4] 2> 1773452 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.u.DirectUpdateHandler2 Committing on
IndexWriter.close() .
[junit4] 2> 1773452 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.u.SolrIndexWriter Calling setCommitData
with IW:org.apache.solr.update.SolrIndexWriter@55af3fcf commitCommandVersion:0
[junit4] 2> 1773543 INFO (zkCallback-8745-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1773543 INFO (zkCallback-8745-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1773543 INFO (zkCallback-8745-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1773829 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data/snapshot_metadata
[junit4] 2> 1773864 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data/index
[junit4] 2> 1773868 INFO (coreCloseExecutor-7161-thread-1)
[n:127.0.0.1:55279_solr ] o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost:55292/solr_hdfs_home/test/core_node2/data
[junit4] 2> 1773871 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1773871 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d3b8ef2: rootName =
solr_55279, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@75498c37
[junit4] 2> 1773882 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1773882 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5993c72c: rootName =
solr_55279, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@35dadb3d
[junit4] 2> 1773886 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1773887 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@308368cb: rootName =
solr_55279, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@75a3de02
[junit4] 2> 1773887 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1773889 INFO (closeThreadPool-8762-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72143612371664904-127.0.0.1:55279_solr-n_0000000000) closing
[junit4] 2> 1773891 INFO
(OverseerStateUpdate-72143612371664904-127.0.0.1:55279_solr-n_0000000000)
[n:127.0.0.1:55279_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:55279_solr
[junit4] 2> 1773895 INFO
(OverseerAutoScalingTriggerThread-72143612371664904-127.0.0.1:55279_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1773898 INFO (closeThreadPool-8762-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72143612371664904-127.0.0.1:55279_solr-n_0000000000) closing
[junit4] 2> 1774000 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.Overseer Overseer
(id=72143612371664904-127.0.0.1:55279_solr-n_0000000000) closing
[junit4] 2> 1774001 INFO (zkCallback-8735-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1774001 INFO (zkCallback-8756-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1774001 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55278_solr
[junit4] 2> 1774002 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.AbstractConnector Stopped ServerConnector@5ea743e4{HTTP/1.1,
(http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1774003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3a17d94d{/solr,null,UNAVAILABLE}
[junit4] 2> 1774003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1774003 INFO (zkCallback-8735-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.Overseer Overseer
(id=72143612371664902-127.0.0.1:55278_solr-n_0000000001) starting
[junit4] 2> 1774004 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase waitForState (test):
[junit4] 2> 1774007 INFO (qtp1162926592-26357) [n:127.0.0.1:55278_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=test&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1774011 INFO
(OverseerStateUpdate-72143612371664902-127.0.0.1:55278_solr-n_0000000001)
[n:127.0.0.1:55278_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:55278_solr
[junit4] 2> 1774018 INFO
(OverseerThreadFactory-7166-thread-1-processing-n:127.0.0.1:55278_solr)
[n:127.0.0.1:55278_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 1774023 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDeleted path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1774038 INFO (qtp1162926592-26357) [n:127.0.0.1:55278_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=test&action=DELETE&wt=javabin&version=2} status=0 QTime=30
[junit4] 2> 1774038 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding
port=55279)
[junit4] 2> 1774038 WARN
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 1774039 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 55279 try number
1 ...
[junit4] 2> 1774039 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z;
git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1774040 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1774040 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1774040 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1774040 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@395a1548{/solr,null,AVAILABLE}
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.AbstractConnector Started ServerConnector@135c17e4{HTTP/1.1,
(http/1.1, h2c)}{127.0.0.1:55279}
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.s.Server Started @1774127ms
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55279}
[junit4] 2> 1774041 ERROR
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
be missing or incomplete.
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.6.0
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1774041 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-04-11T16:49:00.557Z
[junit4] 2> 1774042 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1774043 INFO (zkConnectionManagerCallback-8764-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1774043 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1774043 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1774201 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1774202 WARN
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.u.s.S.config Trusting all certificates configured for
Client@1205be06[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1774202 WARN
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured
for Client@1205be06[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1774207 WARN
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.u.s.S.config Trusting all certificates configured for
Client@47b5572[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1774207 WARN
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured
for Client@47b5572[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1774208 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55273/solr
[junit4] 2> 1774209 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1774212 INFO (zkConnectionManagerCallback-8771-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1774212 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1774315 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1774315 INFO (zkConnectionManagerCallback-8773-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1774315 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 1774328 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1774334 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:55279_solr as DOWN
[junit4] 2> 1774336 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1774336 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55279_solr
[junit4] 2> 1774338 INFO (zkCallback-8756-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1774338 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1774338 INFO (zkCallback-8772-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1774341 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 1774341 WARN
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 1774378 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1774402 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1774409 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1774409 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55279.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1774411 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/tempDir-001/node2/.
[junit4] 2> 1774411 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6])
[n:127.0.0.1:55279_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[test_shard1_replica_n1]
[junit4] 2> 1776024 INFO
(OverseerCollectionConfigSetProcessor-72143612371664902-127.0.0.1:55278_solr-n_0000000001)
[n:127.0.0.1:55278_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1784433 ERROR
(coreContainerWorkExecutor-7168-thread-1-processing-n:127.0.0.1:55279_solr)
[n:127.0.0.1:55279_solr ] o.a.s.c.CoreContainer Error waiting for SolrCore
to be loaded on startup
[junit4] 2> =>
org.apache.solr.cloud.ZkController$NotInClusterStateException: coreNodeName
core_node2 does not exist in shard shard1, ignore the exception if the replica
was deleted
[junit4] 2> at
org.apache.solr.cloud.ZkController.checkStateInZk(ZkController.java:1861)
[junit4] 2> org.apache.solr.cloud.ZkController$NotInClusterStateException:
coreNodeName core_node2 does not exist in shard shard1, ignore the exception if
the replica was deleted
[junit4] 2> at
org.apache.solr.cloud.ZkController.checkStateInZk(ZkController.java:1861)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.ZkController.preRegister(ZkController.java:1760)
~[java/:?]
[junit4] 2> at
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1270)
~[java/:?]
[junit4] 2> at
org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:773)
~[java/:?]
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
~[metrics-core-4.1.5.jar:4.1.5]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_201]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
~[?:1.8.0_201]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
~[?:1.8.0_201]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_201]
[junit4] 2> 1784437 INFO (qtp1162926592-26358) [n:127.0.0.1:55278_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:55278_solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1784504 INFO
(OverseerThreadFactory-7166-thread-2-processing-n:127.0.0.1:55278_solr)
[n:127.0.0.1:55278_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
test
[junit4] 2> 1784693 INFO
(OverseerStateUpdate-72143612371664902-127.0.0.1:55278_solr-n_0000000001)
[n:127.0.0.1:55278_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55278/solr";,
[junit4] 2> "node_name":"127.0.0.1:55278_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1784938 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
x:test_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=test_shard1_replica_n3&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1785036 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1787735 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.s.IndexSchema
Schema name=minimal
[junit4] 2> 1787750 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1787750 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.CoreContainer
Creating SolrCore 'test_shard1_replica_n3' using configuration from configset
conf1, trusted=true
[junit4] 2> 1787751 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55278.solr.core.test.shard1.replica_n3' (registry
'solr.core.test.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6eac4fc3
[junit4] 2> 1787751 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:55292/solr_hdfs_home
[junit4] 2> 1787751 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 1787763 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.SolrCore
[[test_shard1_replica_n3] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_CF4178A5A35718A6-001/tempDir-001/node1/./test_shard1_replica_n3],
dataDir=[hdfs://localhost:55292/solr_hdfs_home/test/core_node4/data/]
[junit4] 2> 1787765 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55292/solr_hdfs_home/test/core_node4/data/snapshot_metadata
[junit4] 2> 1787793 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 1787793 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[33554432] will allocate [1] slabs and use ~[33554432] bytes
[junit4] 2> 1787904 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 1787924 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55292/solr_hdfs_home/test/core_node4/data
[junit4] 2> 1788167 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55292/solr_hdfs_home/test/core_node4/data/index
[junit4] 2> 1788177 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 1788177 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[33554432] will allocate [1] slabs and use ~[33554432] bytes
[junit4] 2> 1788194 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 1788478 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 1788478 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1788478 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=3
[junit4] 2> 1788494 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1788494 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1788663 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@acb4133[test_shard1_replica_n3]
main]
[junit4] 2> 1788666 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1788668 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1788668 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1788669 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1663695590716866560
[junit4] 2> 1788705 INFO
(searcherExecutor-7175-thread-1-processing-n:127.0.0.1:55278_solr
x:test_shard1_replica_n3 c:test s:shard1 r:core_node4) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.SolrCore
[test_shard1_replica_n3] Registered new searcher
Searcher@acb4133[test_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1788767 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/test/terms/shard1 to
Terms{values={core_node4=0}, version=0}
[junit4] 2> 1788767 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/test/leaders/shard1
[junit4] 2> 1788892 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1788893 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1788893 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:55278/solr/test_shard1_replica_n3/
[junit4] 2> 1788904 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1788960 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.SyncStrategy
http://127.0.0.1:55278/solr/test_shard1_replica_n3/ has no replicas
[junit4] 2> 1788960 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/test/leaders/shard1/leader after winning as
/collections/test/leader_elect/shard1/election/72143612371664902-core_node4-n_0000000000
[junit4] 2> 1789063 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:55278/solr/test_shard1_replica_n3/ shard1
[junit4] 2> 1789178 INFO (zkCallback-8735-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789178 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789189 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1789237 INFO (qtp1162926592-26354) [n:127.0.0.1:55278_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=test_shard1_replica_n3&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=4298
[junit4] 2> 1789243 INFO (qtp1162926592-26358) [n:127.0.0.1:55278_solr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at
most 45 seconds. Check all shard replicas
[junit4] 2> 1789352 INFO (zkCallback-8735-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789353 INFO (zkCallback-8735-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789353 INFO (zkCallback-8735-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789384 INFO (qtp1162926592-26358) [n:127.0.0.1:55278_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:55278_solr&wt=javabin&version=2}
status=0 QTime=4947
[junit4] 2> 1789385 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase waitForState (test):
[junit4] 2> 1789394 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1789439 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1789450 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1789474 INFO (qtp1162926592-26355) [n:127.0.0.1:55278_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3 ] o.a.s.c.S.Request
[test_shard1_replica_n3] webapp=/solr path=/select
params={q=*:*&_stateVer_=test:4&_forwardedCount=1&wt=javabin&version=2} hits=0
status=0 QTime=2
[junit4] 2> 1789476 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[CF4178A5A35718A6]) [
] o.a.s.SolrTestCaseJ4 ###Ending testDataDirIsNotReused
[junit4] 2> 1789616 INFO (jetty-closer-8779-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=50506253
[junit4] 2> 1789616 INFO (jetty-closer-8779-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55278_solr
[junit4] 2> 1789617 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=249816233
[junit4] 2> 1789617 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55279_solr
[junit4] 2> 1789617 INFO
(OverseerCollectionConfigSetProcessor-72143612371664902-127.0.0.1:55278_solr-n_0000000001)
[n:127.0.0.1:55278_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1789621 INFO (jetty-closer-8779-thread-2) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1789621 INFO (jetty-closer-8779-thread-2) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:55278_solr as DOWN
[junit4] 2> 1789622 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1789622 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:55279_solr as DOWN
[junit4] 2> 1789720 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1789720 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2a0d8445: rootName =
solr_55279, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@4f38ffaa
[junit4] 2> 1789721 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.c.SolrCore [test_shard1_replica_n3]
CLOSING SolrCore org.apache.solr.core.SolrCore@ce38e9f
[junit4] 2> 1789722 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.test.shard1.replica_n3, tag=SolrCore@ce38e9f
[junit4] 2> 1789722 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@760a731: rootName =
solr_55278, domain = solr.core.test.shard1.replica_n3, service url = null,
agent id = null] for registry solr.core.test.shard1.replica_n3 /
com.codahale.metrics.MetricRegistry@4a4ef47c
[junit4] 2> 1789761 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1789765 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@20bddcd2: rootName =
solr_55279, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@35dadb3d
[junit4] 2> 1789771 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.test.shard1.leader, tag=SolrCore@ce38e9f
[junit4] 2> 1789777 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1789777 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@625a2c20: rootName =
solr_55279, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@75a3de02
[junit4] 2> 1789777 INFO (jetty-closer-8779-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1789779 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.u.DirectUpdateHandler2 Committing on
IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1789815 INFO (zkCallback-8735-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789815 INFO (zkCallback-8735-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789816 INFO (zkCallback-8735-thread-4) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for
collection [test] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1789906 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost:55292/solr_hdfs_home/test/core_node4/data/index
[junit4] 2> 1789911 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:55278_solr ] o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost:55292/solr_hdfs_home/test/core_node4/data
[junit4] 2> 1789914 INFO (coreCloseExecutor-7181-thread-1)
[n:127.0.0.1:552
[...truncated too long message...]
eportManager interrupted
[junit4] 2> 1790953 WARN (BP-829274830-127.0.0.1-1586623737430
heartbeating to localhost/127.0.0.1:55292) [ ] o.a.h.h.s.d.DataNode Ending
block pool service for: Block pool BP-829274830-127.0.0.1-1586623737430
(Datanode Uuid a9abe3cd-db3e-4dfc-819c-c4f96a90998d) service to
localhost/127.0.0.1:55292
[junit4] 2> 1791022 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.w.WebAppContext@1d9bb71f{hdfs,/,null,UNAVAILABLE}{jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/hdfs}
[junit4] 2> 1791025 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@57f453d8{HTTP/1.1,
(http/1.1)}{localhost:0}
[junit4] 2> 1791025 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1791026 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7d26d8b8{static,/static,jar:file:/Users/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.0-tests.jar!/webapps/static,UNAVAILABLE}
[junit4] 2> 1791275 INFO
(SUITE-HDFSCollectionsAPITest-seed#[CF4178A5A35718A6]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> Apr 11, 2020 4:49:18 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 48 leaked
thread(s).
[junit4] 2> Apr 11, 2020 4:49:28 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest:
[junit4] 2> 1) Thread[id=26952, name=nioEventLoopGroup-13-2,
state=TIMED_WAITING, group=TGRP-HDFSCollectionsAPITest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
io.netty.util.concurrent.SingleThreadEventExecutor.confirmShutdown(SingleThreadEventExecutor.java:790)
[junit4] 2> at
io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:525)
[junit4] 2> at
io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
[junit4] 2> at
io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
[junit4] 2> at
io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Apr 11, 2020 4:49:28 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=26952, name=nioEventLoopGroup-13-2,
state=TIMED_WAITING, group=TGRP-HDFSCollectionsAPITest]
[junit4] 2> Apr 11, 2020 4:49:28 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84):
{_version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)),
_root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)),
id=Lucene84}, docValues:{_version_=DocValuesFormat(name=Direct)},
maxPointsInLeafNode=134, maxMBSortInHeap=7.738718376801676,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@47310545),
locale=is-IS, timezone=America/Iqaluit
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201
(64-bit)/cpus=6,threads=3,free=201748016,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestSimDistributedQueue,
TestConfigSetImmutable, ConcurrentCreateCollectionTest, HLLUtilTest,
AutoScalingHandlerTest, CloneFieldUpdateProcessorFactoryTest,
TestSimpleTrackingShardHandler, CloudMLTQParserTest,
PKIAuthenticationIntegrationTest, TestNonDefinedSimilarityFactory,
PrimitiveFieldTypeTest, CircularListTest, TestAnalyzeInfixSuggestions,
TestRangeQuery, IndexSizeTriggerMixedBoundsTest, SpatialRPTFieldTypeTest,
TestSchemaResource, JWTAuthPluginIntegrationTest, TestCorePropertiesReload,
TriLevelCompositeIdRoutingTest, SolrCloudExampleTest, TestRestManager,
SystemInfoHandlerTest, TestCloudSchemaless, HdfsThreadLeakTest,
SolrIndexConfigTest, TestManagedSchemaAPI, DocumentAnalysisRequestHandlerTest,
SimpleCollectionCreateDeleteTest, CacheHeaderTest, TestSolrDeletionPolicy2,
TestReversedWildcardFilterFactory, TestNumericTokenStream,
TestCursorMarkWithoutUniqueKey, TestSmileRequest, RestartWhileUpdatingTest,
HealthCheckHandlerTest, PathHierarchyTokenizerFactoryTest, TestStressLucene,
TestClusterStateMutator, TestCloudPivotFacet, CursorPagingTest,
MetricsHistoryIntegrationTest, TestPseudoReturnFields,
TestSolrCloudWithDelegationTokens, ZookeeperStatusHandlerTest,
ExitableDirectoryReaderTest, TestMultiWordSynonyms,
TestRetrieveFieldsOptimizer, LeaderFailoverAfterPartitionTest,
TestSolrFieldCacheBean, TestLRUCache, DocValuesNotIndexedTest,
TestCloudJSONFacetSKG, FieldMutatingUpdateProcessorTest,
TestIndexingPerformance, TestUseDocValuesAsStored2, BufferStoreTest,
NodeMutatorTest, UpdateParamsTest, TestSolrConfigHandlerConcurrent,
TestExclusionRuleCollectionAccess, ShardRoutingCustomTest, RequestLoggingTest,
TestGraphTermsQParserPlugin, TestDynamicURP, UUIDUpdateProcessorFallbackTest,
SolrJmxReporterTest, AuditLoggerIntegrationTest, QueryElevationComponentTest,
TestHdfsUpdateLog, DateRangeFieldTest, TestSurroundQueryParser,
CdcrOpsAndBoundariesTest, TestMiniSolrCloudClusterSSL, OverseerTest,
SolrRequestParserTest, OverseerRolesTest, BlockJoinFacetRandomTest,
OutputWriterTest, DistribCursorPagingTest, TestAuthenticationFramework,
ClusterStateUpdateTest, TestLegacyNumericUtils, RuleEngineTest,
TestTestInjection, TestPivotHelperCode, DebugComponentTest,
DistributedFacetPivotLongTailTest, TermVectorComponentTest,
TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping,
TestWordDelimiterFilterFactory, TokenizerChainTest, TestLuceneIndexBackCompat,
TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors,
TestJettySolrRunner, ConnectionReuseTest, AddReplicaTest,
AssignBackwardCompatibilityTest, ChaosMonkeyNothingIsSafeTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, DeleteLastCustomShardedReplicaTest,
ForceLeaderWithTlogReplicasTest, FullSolrCloudDistribCmdsTest,
MetricsHistoryWithAuthIntegrationTest, MissingSegmentRecoveryTest,
MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest,
OutOfBoxZkACLAndCredentialsProvidersTest, PackageManagerCLITest,
RecoveryAfterSoftCommitTest, ReindexCollectionTest,
TestQueryingOnDownCollection, TestRebalanceLeaders,
TestTolerantUpdateProcessorCloud, UnloadDistributedZkTest, AssignTest,
AsyncCallRequestStatusResponseTest, TestHdfsCloudBackupRestore,
MetricTriggerIntegrationTest, NodeLostTriggerTest,
SearchRateTriggerIntegrationTest, SearchRateTriggerTest,
TriggerSetPropertiesIntegrationTest, TestSimNodeAddedTrigger,
TestSimPolicyCloud, TestSimTriggerIntegration, CdcrWithNodesRestartsTest,
HDFSCollectionsAPITest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=HDFSCollectionsAPITest -Dtests.seed=CF4178A5A35718A6
-Dtests.slow=true -Dtests.locale=is-IS -Dtests.timezone=America/Iqaluit
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J3 | HDFSCollectionsAPITest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest:
[junit4] > 1) Thread[id=26952, name=nioEventLoopGroup-13-2,
state=TIMED_WAITING, group=TGRP-HDFSCollectionsAPITest]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
io.netty.util.concurrent.SingleThreadEventExecutor.confirmShutdown(SingleThreadEventExecutor.java:790)
[junit4] > at
io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:525)
[junit4] > at
io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
[junit4] > at
io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
[junit4] > at
io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([CF4178A5A35718A6]:0)
[junit4] Completed [585/904 (1!)] on J3 in 32.25s, 1 test, 1 error <<<
FAILURES!
[...truncated 53620 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/850/consoleText
[repro] Revision: 83b34d47900febbd8b5e337a1277b5696c536f78
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=HDFSCollectionsAPITest
-Dtests.seed=CF4178A5A35718A6 -Dtests.slow=true -Dtests.locale=is-IS
-Dtests.timezone=America/Iqaluit -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] HDFSCollectionsAPITest
[repro] ant compile-test
[...truncated 3479 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.HDFSCollectionsAPITest" -Dtests.showOutput=onerror
"-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=CF4178A5A35718A6
-Dtests.slow=true -Dtests.locale=is-IS -Dtests.timezone=America/Iqaluit
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 76 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest
[repro] Exiting with code 0
[...truncated 73 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
