Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22261/
Java: 32bit/jdk1.8.0_172 -server -XX:+UseSerialGC
1 tests failed.
FAILED:
org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest.testDataDirIsNotReused
Error Message:
null Live Nodes: [127.0.0.1:39309_solr, 127.0.0.1:42905_solr] Last available
state: DocCollection(test//collections/test/state.json/5)={
"pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active",
"replicas":{"core_node2":{
"dataDir":"hdfs://localhost.localdomain:40161/data/test/core_node2/data/",
"base_url":"http://127.0.0.1:42905/solr";,
"node_name":"127.0.0.1:42905_solr", "type":"NRT",
"force_set_state":"false",
"ulogDir":"hdfs://localhost.localdomain:40161/data/test/core_node2/data/tlog",
"core":"test_shard1_replica_n1", "shared_storage":"true",
"state":"down", "leader":"true"}}}},
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"1", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError:
null
Live Nodes: [127.0.0.1:39309_solr, 127.0.0.1:42905_solr]
Last available state: DocCollection(test//collections/test/state.json/5)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{"core_node2":{
"dataDir":"hdfs://localhost.localdomain:40161/data/test/core_node2/data/",
"base_url":"http://127.0.0.1:42905/solr";,
"node_name":"127.0.0.1:42905_solr",
"type":"NRT",
"force_set_state":"false",
"ulogDir":"hdfs://localhost.localdomain:40161/data/test/core_node2/data/tlog",
"core":"test_shard1_replica_n1",
"shared_storage":"true",
"state":"down",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([60AA254C8C763187:78B567389F2F9320]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:278)
at
org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest.testDataDirIsNotReused(HDFSCollectionsAPITest.java:90)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14613 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest
[junit4] 2> 2219525 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/init-core-data-001
[junit4] 2> 2219525 WARN
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=59 numCloses=59
[junit4] 2> 2219525 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2219527 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2219527 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-001
[junit4] 2> 2219527 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2219527 INFO (Thread-5428) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2219527 INFO (Thread-5428) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2219528 ERROR (Thread-5428) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 2219627 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36999
[junit4] 2> 2219632 INFO (zkConnectionManagerCallback-6367-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2219635 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1c4f02b{/solr,null,AVAILABLE}
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@c55e4{/solr,null,AVAILABLE}
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@b63513{HTTP/1.1,[http/1.1]}{127.0.0.1:39309}
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@48ae13{HTTP/1.1,[http/1.1]}{127.0.0.1:42905}
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.e.j.s.Server Started @2219672ms
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.e.j.s.Server Started @2219672ms
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39309}
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42905}
[junit4] 2> 2219636 ERROR (jetty-launcher-6364-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2219636 ERROR (jetty-launcher-6364-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-16T06:37:44.791Z
[junit4] 2> 2219636 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-16T06:37:44.791Z
[junit4] 2> 2219637 INFO (zkConnectionManagerCallback-6370-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2219637 INFO (zkConnectionManagerCallback-6371-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2219638 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2219638 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2220698 INFO (jetty-launcher-6364-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36999/solr
[junit4] 2> 2220698 INFO (zkConnectionManagerCallback-6375-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2220700 INFO (zkConnectionManagerCallback-6377-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2220737 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2220737 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42905_solr
[junit4] 2> 2220738 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.c.Overseer Overseer
(id=72209124828315652-127.0.0.1:42905_solr-n_0000000000) starting
[junit4] 2> 2220743 INFO (zkConnectionManagerCallback-6384-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2220744 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36999/solr ready
[junit4] 2> 2220744 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42905_solr
[junit4] 2> 2220744 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2220745 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2220745 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 2220745 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 2220749 INFO (zkCallback-6376-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2220749 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: []
[junit4] 2> 2220753 INFO (zkCallback-6383-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2220760 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2220761 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 2220761 DEBUG (ScheduledTrigger-9902-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2220765 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2220787 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2220793 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2220793 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2220795 INFO (jetty-launcher-6364-thread-1)
[n:127.0.0.1:42905_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-001/node1/.
[junit4] 2> 2220945 INFO (jetty-launcher-6364-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36999/solr
[junit4] 2> 2220946 INFO (zkConnectionManagerCallback-6389-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2220947 INFO (zkConnectionManagerCallback-6391-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2220951 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2220951 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2220952 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2220952 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39309_solr
[junit4] 2> 2220953 INFO (zkCallback-6383-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2220953 INFO (zkCallback-6376-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2220953 INFO (zkCallback-6390-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2220968 INFO (zkConnectionManagerCallback-6398-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2220969 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2220970 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36999/solr ready
[junit4] 2> 2220970 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2220983 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39309.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2220989 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39309.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2220990 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39309.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2220990 INFO (jetty-launcher-6364-thread-2)
[n:127.0.0.1:39309_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-001/node2/.
[junit4] 2> 2221021 INFO (zkConnectionManagerCallback-6401-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 2221192 WARN
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 2221202 WARN
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2221203 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 2221218 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Extract
jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_localdomain_44017_hdfs____.fqixqo/webapp
[junit4] 2> 2221762 DEBUG (ScheduledTrigger-9902-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2221870 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:44017
[junit4] 2> 2221985 WARN
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2221986 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 2221996 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Extract
jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_39065_datanode____.hli7kg/webapp
[junit4] 2> 2222479 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:39065
[junit4] 2> 2222543 WARN
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2222544 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 2222556 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Extract
jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_46789_datanode____qg1i1p/webapp
[junit4] 2> 2222772 DEBUG (ScheduledTrigger-9902-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2222774 ERROR (DataNode:
[[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data1/,
[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data2/]]
heartbeating to localhost.localdomain/127.0.0.1:40161) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 2222785 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x836ea4529695a: from storage
DS-94c36a8f-7c49-4171-8647-f97ccc71d14b node
DatanodeRegistration(127.0.0.1:40343,
datanodeUuid=34b0e03b-ee56-45cf-a79b-8a402e8021f8, infoPort=41419,
infoSecurePort=0, ipcPort=42239,
storageInfo=lv=-56;cid=testClusterID;nsid=2048969007;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 2222786 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x836ea4529695a: from storage
DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba node
DatanodeRegistration(127.0.0.1:40343,
datanodeUuid=34b0e03b-ee56-45cf-a79b-8a402e8021f8, infoPort=41419,
infoSecurePort=0, ipcPort=42239,
storageInfo=lv=-56;cid=testClusterID;nsid=2048969007;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 2223072 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46789
[junit4] 2> 2223365 ERROR (DataNode:
[[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data3/,
[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data4/]]
heartbeating to localhost.localdomain/127.0.0.1:40161) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 2223368 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x836ea67f002f3: from storage
DS-5932ec01-8871-43c5-9beb-14913c84fc97 node
DatanodeRegistration(127.0.0.1:42519,
datanodeUuid=0672a40e-fe9f-49b8-a87a-92358e2e012c, infoPort=37895,
infoSecurePort=0, ipcPort=44579,
storageInfo=lv=-56;cid=testClusterID;nsid=2048969007;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 2223368 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x836ea67f002f3: from storage
DS-04185558-842c-4220-924d-ea1d81af249d node
DatanodeRegistration(127.0.0.1:42519,
datanodeUuid=0672a40e-fe9f-49b8-a87a-92358e2e012c, infoPort=37895,
infoSecurePort=0, ipcPort=44579,
storageInfo=lv=-56;cid=testClusterID;nsid=2048969007;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 2223416 INFO (zkConnectionManagerCallback-6406-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2223417 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2223417 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36999/solr ready
[junit4] 2> 2223463 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.SolrTestCaseJ4 ###Starting testDataDirIsNotReused
[junit4] 2> 2223464 INFO (qtp15718449-23453) [n:127.0.0.1:39309_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42905_solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2223465 INFO (OverseerThreadFactory-9904-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection test
[junit4] 2> 2223569 INFO
(OverseerStateUpdate-72209124828315652-127.0.0.1:42905_solr-n_0000000000) [
] 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:42905/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2223771 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2223771 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
x:test_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2223772 DEBUG (ScheduledTrigger-9902-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2224773 DEBUG (ScheduledTrigger-9902-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2224782 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 8.0.0
[junit4] 2> 2224805 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema
[test_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2224807 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224808 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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 collection
test, trusted=true
[junit4] 2> 2224808 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.core.test.shard1.replica_n1' (registry
'solr.core.test.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2224808 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost.localdomain:40161/data
[junit4] 2> 2224808 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 2224808 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2224808 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-001/node1/test_shard1_replica_n1],
dataDir=[hdfs://localhost.localdomain:40161/data/test/core_node2/data/]
[junit4] 2> 2224809 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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.localdomain:40161/data/test/core_node2/data/snapshot_metadata
[junit4] 2> 2224822 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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.localdomain:40161/data/test/core_node2/data
[junit4] 2> 2224838 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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.localdomain:40161/data/test/core_node2/data/index
[junit4] 2> 2224869 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|FINALIZED]]}
size 0
[junit4] 2> 2224871 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|FINALIZED],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|FINALIZED]]}
size 0
[junit4] 2> 2224917 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224917 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224917 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=3
[junit4] 2> 2224926 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2224926 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2224937 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@a4e92[test_shard1_replica_n1] main]
[junit4] 2> 2224938 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224938 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224939 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224939 INFO
(searcherExecutor-9916-thread-1-processing-n:127.0.0.1:42905_solr
x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:42905_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@a4e92[test_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2224940 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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
1603410140955934720
[junit4] 2> 2224943 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224945 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224956 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224957 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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:42905/solr/test_shard1_replica_n1/
[junit4] 2> 2224957 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2224957 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:42905/solr/test_shard1_replica_n1/ has no replicas
[junit4] 2> 2224957 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2224959 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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:42905/solr/test_shard1_replica_n1/ shard1
[junit4] 2> 2225060 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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> 2225061 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] 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=1290
[junit4] 2> 2225062 INFO (qtp15718449-23453) [n:127.0.0.1:39309_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 2225161 INFO (zkCallback-6376-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> 2225466 INFO
(OverseerCollectionConfigSetProcessor-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 2225773 DEBUG (ScheduledTrigger-9902-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2226063 INFO (qtp15718449-23453) [n:127.0.0.1:39309_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42905_solr&wt=javabin&version=2}
status=0 QTime=2599
[junit4] 2> 2226069 INFO (qtp18191106-23442) [n:127.0.0.1:42905_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> 2226076 INFO (qtp18191106-23442) [n:127.0.0.1:42905_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 (1603410142135582720)]} 0 11
[junit4] 2> 2226078 INFO (qtp18191106-23450) [n:127.0.0.1:42905_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 (1603410142148165632)]} 0 1
[junit4] 2> 2226079 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1603410142150262784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2226079 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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@d8f977
commitCommandVersion:1603410142150262784
[junit4] 2> 2226090 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW]]}
size 0
[junit4] 2> 2226090 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741827_1003 size 204
[junit4] 2> 2226095 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226096 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|FINALIZED]]}
size 0
[junit4] 2> 2226102 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|FINALIZED]]}
size 0
[junit4] 2> 2226103 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741829_1005 size 85
[junit4] 2> 2226108 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226109 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|FINALIZED]]}
size 0
[junit4] 2> 2226118 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226118 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741831_1007 size 173
[junit4] 2> 2226124 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226126 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741832_1008 size 83
[junit4] 2> 2226138 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226139 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741833_1009 size 111
[junit4] 2> 2226145 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|FINALIZED]]}
size 0
[junit4] 2> 2226145 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741834_1010 size 69
[junit4] 2> 2226152 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|FINALIZED]]}
size 0
[junit4] 2> 2226153 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741835_1011 size 81
[junit4] 2> 2226159 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226160 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741836_1012 size 103
[junit4] 2> 2226168 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226170 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741837_1013 size 112
[junit4] 2> 2226174 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741838_1014{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226174 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741838_1014 size 76
[junit4] 2> 2226180 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741839_1015{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|FINALIZED]]}
size 0
[junit4] 2> 2226180 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741839_1015 size 122
[junit4] 2> 2226185 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741840_1016{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226186 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741840_1016 size 100
[junit4] 2> 2226191 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741841_1017{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226191 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741841_1017 size 101
[junit4] 2> 2226199 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741842_1018{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226200 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741842_1018{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 0
[junit4] 2> 2226233 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1247048[test_shard1_replica_n1]
main]
[junit4] 2> 2226235 INFO
(searcherExecutor-9916-thread-1-processing-n:127.0.0.1:42905_solr
x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:42905_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@1247048[test_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 2226236 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW]]}
size 75
[junit4] 2> 2226237 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741826_1002 size 139
[junit4] 2> 2226237 INFO (qtp18191106-23451) [n:127.0.0.1:42905_solr
c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2226237 INFO (qtp18191106-23451) [n:127.0.0.1:42905_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 159
[junit4] 2> 2226246 INFO (qtp18191106-23450) [n:127.0.0.1:42905_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 (1603410142316986368)]} 0 7
[junit4] 2> 2226246 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@48ae13{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2226249 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18569219
[junit4] 2> 2226249 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2226249 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5a464c: rootName =
solr_42905, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@986011
[junit4] 2> 2226258 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2226258 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1139405: rootName =
solr_42905, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@a49a20
[junit4] 2> 2226262 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2226262 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@faebba: rootName =
solr_42905, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@17c506a
[junit4] 2> 2226263 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42905_solr
[junit4] 2> 2226264 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 2226264 INFO (zkCallback-6383-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2226264 INFO (zkCallback-6397-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2226264 INFO (zkCallback-6405-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2226264 INFO (zkCallback-6390-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2226264 INFO (zkCallback-6376-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2226265 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.c.SolrCore [test_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@12d535b
[junit4] 2> 2226265 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.test.shard1.replica_n1, tag=12d535b
[junit4] 2> 2226265 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7c818b: rootName =
solr_42905, 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@6e0293
[junit4] 2> 2226274 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.test.shard1.leader, tag=12d535b
[junit4] 2> 2226281 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741843_1019{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-04185558-842c-4220-924d-ea1d81af249d:NORMAL:127.0.0.1:42519|FINALIZED]]}
size 75
[junit4] 2> 2226281 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741843_1019 size 75
[junit4] 2> 2226283 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost.localdomain:40161/data/test/core_node2/data
[junit4] 2> 2226283 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost.localdomain:40161/data/test/core_node2/data/index
[junit4] 2> 2226283 INFO (coreCloseExecutor-9921-thread-1)
[n:127.0.0.1:42905_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1]
o.a.s.s.h.HdfsDirectory Closing hdfs directory
hdfs://localhost.localdomain:40161/data/test/core_node2/data/snapshot_metadata
[junit4] 2> 2226283 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.Overseer Overseer
(id=72209124828315652-127.0.0.1:42905_solr-n_0000000000) closing
[junit4] 2> 2226284 INFO
(OverseerStateUpdate-72209124828315652-127.0.0.1:42905_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42905_solr
[junit4] 2> 2226284 ERROR
(OldIndexDirectoryCleanupThreadForCore-test_shard1_replica_n1) [ ]
o.a.s.c.HdfsDirectoryFactory Error checking for old index directories to
clean-up.
[junit4] 2> java.io.IOException: Filesystem closed
[junit4] 2> at
org.apache.hadoop.hdfs.DFSClient.checkOpen(DFSClient.java:808)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DFSClient.listPaths(DFSClient.java:2083)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DFSClient.listPaths(DFSClient.java:2069)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DistributedFileSystem.listStatusInternal(DistributedFileSystem.java:791)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DistributedFileSystem.access$700(DistributedFileSystem.java:106)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DistributedFileSystem$18.doCall(DistributedFileSystem.java:853)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DistributedFileSystem$18.doCall(DistributedFileSystem.java:849)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.fs.FileSystemLinkResolver.resolve(FileSystemLinkResolver.java:81)
~[hadoop-common-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DistributedFileSystem.listStatus(DistributedFileSystem.java:860)
~[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.fs.FileSystem.listStatus(FileSystem.java:1517)
~[hadoop-common-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.fs.FileSystem.listStatus(FileSystem.java:1557)
~[hadoop-common-2.7.4.jar:?]
[junit4] 2> at
org.apache.solr.core.HdfsDirectoryFactory.cleanupOldIndexDirectories(HdfsDirectoryFactory.java:528)
~[java/:?]
[junit4] 2> at
org.apache.solr.core.SolrCore.lambda$cleanupOldIndexDirectories$21(SolrCore.java:3097)
~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 2226284 WARN
(OverseerAutoScalingTriggerThread-72209124828315652-127.0.0.1:42905_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 2226289 DEBUG
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 2226289 DEBUG
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 2226290 DEBUG
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 2226290 DEBUG
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 2226290 DEBUG
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 2226290 DEBUG
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2226291 INFO (zkCallback-6390-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39309_solr
[junit4] 2> 2226291 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@c55e4{/solr,null,UNAVAILABLE}
[junit4] 2> 2226291 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2226291 INFO (zkCallback-6390-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72209124828315655-127.0.0.1:39309_solr-n_0000000001) starting
[junit4] 2> 2226293 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2226293 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 2226293 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 2226294 INFO (zkCallback-6405-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: [1])
[junit4] 2> 2226294 INFO (zkCallback-6405-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: [1])
[junit4] 2> 2226294 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:39309_solr]
[junit4] 2> 2226294 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path:
127.0.0.1:42905_solr
[junit4] 2> 2226294 INFO (qtp15718449-23441) [n:127.0.0.1:39309_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> 2226295 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2226295 DEBUG
(OverseerAutoScalingTriggerThread-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 2226296 DEBUG (ScheduledTrigger-9924-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2226296 DEBUG (ScheduledTrigger-9924-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:42905_solr
[junit4] 2> 2226296 INFO (OverseerThreadFactory-9926-thread-1) [ ]
o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 2227002 INFO (qtp15718449-23441) [n:127.0.0.1:39309_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=test&action=DELETE&wt=javabin&version=2} status=0 QTime=707
[junit4] 2> 2227002 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z;
git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@10c7ae9{/solr,null,AVAILABLE}
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@16189b2{HTTP/1.1,[http/1.1]}{127.0.0.1:42905}
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.e.j.s.Server Started @2227039ms
[junit4] 2> 2227003 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42905}
[junit4] 2> 2227003 ERROR
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
be missing or incomplete.
[junit4] 2> 2227004 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2227004 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 8.0.0
[junit4] 2> 2227004 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 2227004 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2227004 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-16T06:37:52.159Z
[junit4] 2> 2227005 INFO (zkConnectionManagerCallback-6408-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2227006 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2227297 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2227384 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36999/solr
[junit4] 2> 2227385 INFO (zkConnectionManagerCallback-6412-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2227387 INFO (zkConnectionManagerCallback-6414-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2227390 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2227391 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2227391 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2227391 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42905_solr
[junit4] 2> 2227392 INFO (zkCallback-6397-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2227392 INFO (zkCallback-6405-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2227392 INFO (zkCallback-6390-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2227392 INFO (zkCallback-6413-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2227408 INFO (zkConnectionManagerCallback-6421-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2227409 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2227410 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36999/solr ready
[junit4] 2> 2227410 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2227423 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2227430 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2227430 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42905.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2227432 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-001/node1/.
[junit4] 2> 2227432 INFO
(TEST-HDFSCollectionsAPITest.testDataDirIsNotReused-seed#[60AA254C8C763187])
[n:127.0.0.1:42905_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[test_shard1_replica_n1]
[junit4] 2> 2228297 INFO
(OverseerCollectionConfigSetProcessor-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] 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> 2228298 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2229298 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2230299 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2231299 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2232299 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2233299 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2234299 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2235299 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2236300 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2237300 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2237434 ERROR
(coreContainerWorkExecutor-9928-thread-1-processing-n:127.0.0.1:42905_solr)
[n:127.0.0.1:42905_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:1755)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.ZkController.preRegister(ZkController.java:1654)
~[java/:?]
[junit4] 2> at
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1133)
~[java/:?]
[junit4] 2> at
org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:686)
~[java/:?]
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
~[metrics-core-3.2.6.jar:3.2.6]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 2237435 INFO (qtp15718449-23452) [n:127.0.0.1:39309_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:39309_solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2237437 INFO (OverseerThreadFactory-9926-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection test
[junit4] 2> 2237540 INFO
(OverseerStateUpdate-72209124828315655-127.0.0.1:39309_solr-n_0000000001) [
] 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:39309/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2237742 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238300 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2238752 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 8.0.0
[junit4] 2> 2238758 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.s.IndexSchema
[test_shard1_replica_n3] Schema name=minimal
[junit4] 2> 2238761 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238761 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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 collection
test, trusted=true
[junit4] 2> 2238762 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39309.solr.core.test.shard1.replica_n3' (registry
'solr.core.test.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b51e31
[junit4] 2> 2238762 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost.localdomain:40161/data
[junit4] 2> 2238762 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 2238762 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2238762 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-001/node2/test_shard1_replica_n3],
dataDir=[hdfs://localhost.localdomain:40161/data/test/core_node4/data/]
[junit4] 2> 2238763 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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.localdomain:40161/data/test/core_node4/data/snapshot_metadata
[junit4] 2> 2238776 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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.localdomain:40161/data/test/core_node4/data
[junit4] 2> 2238793 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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.localdomain:40161/data/test/core_node4/data/index
[junit4] 2> 2238813 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42519 is added to
blk_1073741844_1020{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-94c36a8f-7c49-4171-8647-f97ccc71d14b:NORMAL:127.0.0.1:40343|RBW],
ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW]]}
size 0
[junit4] 2> 2238813 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40343 is added to
blk_1073741844_1020{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5932ec01-8871-43c5-9beb-14913c84fc97:NORMAL:127.0.0.1:42519|RBW],
ReplicaUC[[DISK]DS-3d3747e1-e369-450f-8ce7-d386ea5cb2ba:NORMAL:127.0.0.1:40343|FINALIZED]]}
size 0
[junit4] 2> 2238814 WARN (DataStreamer for file
/data/test/core_node4/data/index/pending_segments_1) [ ] o.a.h.h.DFSClient
Caught exception
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.lang.Object.wait(Native Method) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.join(Thread.java:1252)
~[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.join(Thread.java:1326)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.closeResponder(DFSOutputStream.java:716)
[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.endBlock(DFSOutputStream.java:476)
[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:652)
[hadoop-hdfs-2.7.4.jar:?]
[junit4] 2> 2238862 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238862 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238862 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=3
[junit4] 2> 2238888 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2238888 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2238900 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.s.SolrIndexSearcher Opening [Searcher@16e6537[test_shard1_replica_n3]
main]
[junit4] 2> 2238901 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238901 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238901 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238902 INFO
(searcherExecutor-9936-thread-1-processing-n:127.0.0.1:39309_solr
x:test_shard1_replica_n3 c:test s:shard1 r:core_node4) [n:127.0.0.1:39309_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@16e6537[test_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2238902 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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
1603410155596152832
[junit4] 2> 2238906 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238907 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238907 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238907 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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:39309/solr/test_shard1_replica_n3/
[junit4] 2> 2238907 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2238907 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] o.a.s.c.SyncStrategy
http://127.0.0.1:39309/solr/test_shard1_replica_n3/ has no replicas
[junit4] 2> 2238907 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2238908 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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:39309/solr/test_shard1_replica_n3/ shard1
[junit4] 2> 2239009 INFO (qtp15718449-23443) [n:127.0.0.1:39309_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> 2239110 INFO (zkCallback-6390-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> 2239259 INFO (qtp15718449-23443) [n:127.0.0.1:39309_solr
c:test s:shard1 r:core_node4 x:test_shard1_replica_n3] 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=1517
[junit4] 2> 2239260 INFO (qtp15718449-23452) [n:127.0.0.1:39309_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 2239261 INFO (qtp15718449-23452) [n:127.0.0.1:39309_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=test&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:39309_solr&wt=javabin&version=2}
status=0 QTime=1825
[junit4] 2> 2239300 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2239437 INFO
(OverseerCollectionConfigSetProcessor-72209124828315655-127.0.0.1:39309_solr-n_0000000001)
[ ] 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> 2240300 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2241300 DEBUG (ScheduledTrigger-9924-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2242300 DEBUG (ScheduledTrigger-9924-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with cur
[...truncated too long message...]
CollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 2330199 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 2330299 WARN (DataNode:
[[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data1/,
[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data2/]]
heartbeating to localhost.localdomain/127.0.0.1:40161) [ ]
o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager
interrupted
[junit4] 2> 2330299 WARN (DataNode:
[[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data1/,
[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001/tempDir-002/hdfsBaseDir/data/data2/]]
heartbeating to localhost.localdomain/127.0.0.1:40161) [ ]
o.a.h.h.s.d.DataNode Ending block pool service for: Block pool
BP-448051042-88.99.242.108-1529131066193 (Datanode Uuid
34b0e03b-ee56-45cf-a79b-8a402e8021f8) service to
localhost.localdomain/127.0.0.1:40161
[junit4] 2> 2330304 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ] o.m.log
Stopped
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:0
[junit4] 2> 2330415 INFO
(SUITE-HDFSCollectionsAPITest-seed#[60AA254C8C763187]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:36999 36999
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HDFSCollectionsAPITest_60AA254C8C763187-001
[junit4] 2> Jun 16, 2018 6:39:35 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 66 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{id=PostingsFormat(name=MockRandom)},
docValues:{_version_=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=957,
maxMBSortInHeap=6.660713013085619,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@26ac4),
locale=en-ZA, timezone=Pacific/Port_Moresby
[junit4] 2> NOTE: Linux 4.13.0-41-generic i386/Oracle Corporation
1.8.0_172 (32-bit)/cpus=8,threads=2,free=96042896,total=349327360
[junit4] 2> NOTE: All tests run in this JVM: [TestPolicyCloud,
TestTolerantSearch, TestRangeQuery, MultiTermTest,
TestCopyFieldCollectionResource, TestLeaderInitiatedRecoveryThread,
LeaderVoteWaitTimeoutTest, CopyFieldTest, TestDistributedMissingSort,
DeleteReplicaTest, PingRequestHandlerTest, TestDistribDocBasedVersion,
TestReplicaProperties, TestCustomDocTransformer, TestDistribStateManager,
BaseCdcrDistributedZkTest, TestPointFields, CoreAdminRequestStatusTest,
TestRebalanceLeaders, ClassificationUpdateProcessorFactoryTest,
TestFastLRUCache, TestSortableTextField, TestJmxIntegration,
TestEmbeddedSolrServerSchemaAPI, TestLazyCores, TestFaceting,
SolrSlf4jReporterTest, SpellCheckComponentTest, TestSolrCoreProperties,
TestSolrDeletionPolicy2, SpatialRPTFieldTypeTest, TestStressReorder,
MultiThreadedOCPTest, TestSolrFieldCacheBean, MoveReplicaHDFSTest,
SecurityConfHandlerTest, TestDynamicLoading, WordBreakSolrSpellCheckerTest,
AutoscalingHistoryHandlerTest, CdcrReplicationDistributedZkTest,
PreAnalyzedFieldManagedSchemaCloudTest, TestSuggestSpellingConverter,
DataDrivenBlockJoinTest, ReturnFieldsTest,
TestSolrCloudWithSecureImpersonation, AssignBackwardCompatibilityTest,
StatelessScriptUpdateProcessorFactoryTest, DirectSolrSpellCheckerTest,
TestExclusionRuleCollectionAccess, TestPKIAuthenticationPlugin,
IndexSchemaRuntimeFieldTest, TestTrackingShardHandlerFactory,
CloudExitableDirectoryReaderTest, AnalysisAfterCoreReloadTest, TestQueryTypes,
ZkStateWriterTest, TestFieldTypeResource, ForceLeaderTest,
SolrGraphiteReporterTest, HttpPartitionTest, DocValuesMissingTest,
TimeZoneUtilsTest, SolrRrdBackendFactoryTest, TestQuerySenderListener,
CollectionTooManyReplicasTest, ScheduledMaintenanceTriggerTest,
TestRandomDVFaceting, TestCoreDiscovery, FileBasedSpellCheckerTest,
TestMaxTokenLenTokenizer, SolrTestCaseJ4Test,
TestManagedSynonymGraphFilterFactory, SpellingQueryConverterTest,
TestMultiWordSynonyms, HdfsBasicDistributedZkTest,
BigEndianAscendingWordSerializerTest, ExecutePlanActionTest,
DistributedSpellCheckComponentTest, TestCloudJSONFacetJoinDomain,
ReplicaListTransformerTest, TestSubQueryTransformerCrossCore,
MetricsHandlerTest, TermVectorComponentTest, ChangedSchemaMergeTest,
DistribCursorPagingTest, TestBlendedInfixSuggestions, SearchHandlerTest,
TestFieldCollectionResource, DocValuesTest, TestFieldCacheVsDocValues,
HdfsTlogReplayBufferedWhileIndexingTest, AnalyticsMergeStrategyTest,
TestLuceneIndexBackCompat, TestDelegationWithHadoopAuth, SimpleMLTQParserTest,
TestCloudPseudoReturnFields, DistanceUnitsTest, TestHdfsUpdateLog,
TestSegmentSorting, SolrCloudExampleTest, ClusterStateTest, ActionThrottleTest,
TestSQLHandlerNonCloud, StressHdfsTest, RestartWhileUpdatingTest,
SearchHandlerTest, DistributedFacetPivotLongTailTest, TestDocTermOrds,
SliceStateTest, UniqFieldsUpdateProcessorFactoryTest,
TestConfigSetsAPIZkFailure, ZkShardTermsTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, ConjunctionSolrSpellCheckerTest,
TestJsonFacetsWithNestedObjects, MoreLikeThisHandlerTest,
DistributedFacetSimpleRefinementLongTailTest, SparseHLLTest,
TestNumericTokenStream, TestSmileRequest, LukeRequestHandlerTest,
TestScoreJoinQPScore, TestUtils, TestSolrCoreSnapshots, TransactionLogTest,
TestDFRSimilarityFactory, TestClusterProperties, TestApiFramework,
ClusterStateUpdateTest, DocValuesMultiTest, TestPayloadCheckQParserPlugin,
SpatialFilterTest, NodeAddedTriggerIntegrationTest, ChaosMonkeySafeLeaderTest,
BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OverseerTest,
LeaderElectionIntegrationTest, RecoveryZkTest, TestReplicationHandler,
TestRandomFaceting, LeaderElectionTest, TestZkChroot, ZkCLITest,
TestHashPartitioner, TermVectorComponentDistributedTest, TestRealTimeGet,
TestStressVersions, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial,
StatsComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest,
PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering,
TestIndexSearcher, SoftAutoCommitTest, HighlighterConfigTest, SOLR749Test,
AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery,
ResponseLogComponentTest, BadComponentTest, TestMergePolicyConfig, SampleTest,
TestSearchPerf, NumericFieldsTest, MinimalSchemaTest,
TestElisionMultitermQuery, ExternalFileFieldSortTest,
TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestPhraseSuggestions,
SpellPossibilityIteratorTest, TestCodecSupport, SynonymTokenizerTest,
TestXIncludeConfig, EchoParamsTest, TestSweetSpotSimilarityFactory,
TestPerFieldSimilarity, TestBM25SimilarityFactory, ResourceLoaderTest,
OpenExchangeRatesOrgProviderTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest,
CircularListTest, DistributedIntervalFacetingTest, CollectionStateFormat2Test,
CollectionsAPISolrJTest, DocValuesNotIndexedTest, HealthCheckHandlerTest,
HttpPartitionOnCommitTest, MoveReplicaTest, NodeMutatorTest,
OutOfBoxZkACLAndCredentialsProvidersTest,
OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest,
OverseerStatusTest, PeerSyncReplicationTest, RemoteQueryErrorTest,
ReplaceNodeNoTargetTest, SSLMigrationTest, SaslZkACLProviderTest,
TestCloudJSONFacetSKG, TestCloudRecovery, TestConfigSetsAPI,
TestOnReconnectListenerSupport, TestPrepRecovery, TestPullReplicaErrorHandling,
TestTolerantUpdateProcessorCloud, TestTolerantUpdateProcessorRandomCloud,
TestUtilizeNode, TriLevelCompositeIdRoutingTest, ZkFailoverTest,
ConcurrentDeleteAndCreateCollectionTest, ShardSplitTest,
TestHdfsCloudBackupRestore, TestRequestStatusCollectionAPI,
NodeLostTriggerTest, NodeMarkersRegistrationTest,
ScheduledTriggerIntegrationTest, ScheduledTriggerTest,
SearchRateTriggerIntegrationTest, TriggerCooldownIntegrationTest,
TriggerSetPropertiesIntegrationTest, TestExecutePlanAction, TestLargeCluster,
TestNodeAddedTrigger, TestSimDistributedQueue, TestTriggerIntegration,
CdcrBidirectionalTest, HDFSCollectionsAPITest]
[junit4] Completed [641/817 (1!)] on J2 in 120.78s, 1 test, 1 failure <<<
FAILURES!
[...truncated 46958 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22261/consoleText
[repro] Revision: 228a84fd6db3ef5fc1624d69e1c82a1f02c51352
[repro] Ant options: "-Dargs=-server -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=HDFSCollectionsAPITest
-Dtests.method=testDataDirIsNotReused -Dtests.seed=60AA254C8C763187
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-ZA
-Dtests.timezone=Pacific/Port_Moresby -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] HDFSCollectionsAPITest
[repro] ant compile-test
[...truncated 3300 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.HDFSCollectionsAPITest" -Dtests.showOutput=onerror
"-Dargs=-server -XX:+UseSerialGC" -Dtests.seed=60AA254C8C763187
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-ZA
-Dtests.timezone=Pacific/Port_Moresby -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[...truncated 71 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.hdfs.HDFSCollectionsAPITest
[repro] Exiting with code 0
[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
