Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1565/
1 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsBasicDistributedZkTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:358) at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3091)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1612) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:1004) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:867) at
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1140)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:686)
at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266) at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not
released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory
at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:358)
at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3091)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1612)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1004)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:867)
at
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1140)
at
org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:686)
at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([96DD454BA974C6D2]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:304)
at sun.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:897)
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 13707 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.HdfsBasicDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/init-core-data-001
[junit4] 2> 795145 WARN
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 795145 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 795155 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 795155 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 795155 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_ihd/
[junit4] 2> 797468 WARN
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your
platform... using builtin-java classes where applicable
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 800134 WARN
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 800936 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log Logging to org.apache.logging.slf4j.Log4jLogger@28f4938b via
org.mortbay.log.Slf4jLog
[junit4] 2> 800971 WARN
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 802263 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 802366 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_50198_hdfs____ppz8lb/webapp
[junit4] 2> 804082 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:50198
[junit4] 2> 805972 WARN
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 805993 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 806044 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_46289_datanode____.r6ms2w/webapp
[junit4] 2> 807674 INFO
(SUITE-HdfsBasicDistributedZkTest-seed#[96DD454BA974C6D2]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46289
[junit4] 2> 810479 ERROR (DataNode:
[[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/tempDir-001/hdfsBaseDir/data/data1/,
[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/tempDir-001/hdfsBaseDir/data/data2/]]
heartbeating to localhost/127.0.0.1:52795) [ ]
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> 810848 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x682519e2745314: from storage
DS-85c19281-3627-439f-9fc8-e67f1122b1ad node
DatanodeRegistration(127.0.0.1:39789,
datanodeUuid=e57957b9-ceb1-48f4-a124-132cbe496675, infoPort=42495,
infoSecurePort=0, ipcPort=42525,
storageInfo=lv=-56;cid=testClusterID;nsid=1432551165;c=0), blocks: 0,
hasStaleStorage: true, processing time: 4 msecs
[junit4] 2> 810855 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x682519e2745314: from storage
DS-fb56f893-121b-4cb6-99c7-21247910e34d node
DatanodeRegistration(127.0.0.1:39789,
datanodeUuid=e57957b9-ceb1-48f4-a124-132cbe496675, infoPort=42495,
infoSecurePort=0, ipcPort=42525,
storageInfo=lv=-56;cid=testClusterID;nsid=1432551165;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 811446 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 811450 INFO (Thread-192) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 811450 INFO (Thread-192) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 811528 ERROR (Thread-192) [ ] 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> 811550 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.ZkTestServer start zk server on port:60580
[junit4] 2> 811589 INFO (zkConnectionManagerCallback-58864-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811611 INFO (zkConnectionManagerCallback-58866-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811628 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 811637 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 811639 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 811648 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 811650 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 811651 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 811656 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 811659 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 811660 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 811663 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 811671 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 811680 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 811938 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
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> 811941 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 811941 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 811941 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 811943 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3964918c{/_ihd,null,AVAILABLE}
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@53cf352a{HTTP/1.1,[http/1.1]}{127.0.0.1:41076}
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.Server Started @812264ms
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:52795/hdfs__localhost_52795__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001_tempDir-002_control_data,
replicaType=NRT, hostContext=/_ihd, hostPort=41076,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/control-001/cores}
[junit4] 2> 811944 ERROR
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 811944 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 811945 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-15T11:31:34.781Z
[junit4] 2> 811956 INFO (zkConnectionManagerCallback-58868-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811959 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 811959 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/control-001/solr.xml
[junit4] 2> 811967 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 811967 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 811983 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 812179 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60580/solr
[junit4] 2> 812181 INFO (zkConnectionManagerCallback-58872-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 812183 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x106d34310080003, likely client has closed socket
[junit4] 2> 812347 INFO (zkConnectionManagerCallback-58874-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 812485 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 812486 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:41076__ihd
[junit4] 2> 812487 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.c.Overseer Overseer
(id=73978728883421188-127.0.0.1:41076__ihd-n_0000000000) starting
[junit4] 2> 812529 INFO (zkConnectionManagerCallback-58881-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 812611 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:60580/solr ready
[junit4] 2> 812639 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41076__ihd
[junit4] 2> 812679 INFO (zkCallback-58873-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 812715 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 812725 INFO (zkCallback-58880-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 813042 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 813105 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 813106 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 813112 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41076__ihd ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/control-001/cores
[junit4] 2> 813467 INFO (zkConnectionManagerCallback-58887-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 813468 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 813469 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60580/solr ready
[junit4] 2> 813475 INFO (qtp1789081756-107633) [n:127.0.0.1:41076__ihd
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:41076__ihd&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 813504 INFO (OverseerThreadFactory-5913-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 813658 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core
create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 813658 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 4 transient cores
[junit4] 2> 814731 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 814818 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 815097 WARN (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.EnumField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 815123 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 815210 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from collection control_collection, trusted=true
[junit4] 2> 815211 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 815380 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:52795/solr_hdfs_home
[junit4] 2> 815380 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 815380 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 815380 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[hdfs://localhost:52795/solr_hdfs_home/control_collection/core_node2/data/]
[junit4] 2> 815383 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/control_collection/core_node2/data/snapshot_metadata
[junit4] 2> 815440 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 815440 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 815440 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 816964 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 816980 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/control_collection/core_node2/data
[junit4] 2> 817103 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/control_collection/core_node2/data/index
[junit4] 2> 817114 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 817114 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 817114 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 817168 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 817169 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=88.4326171875,
floorSegmentMB=1.5029296875, forceMergeDeletesPctAllowed=25.025278258247063,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6050041663921454
[junit4] 2> 817754 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39789 is added to
blk_1073741825_1001{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-fb56f893-121b-4cb6-99c7-21247910e34d:NORMAL:127.0.0.1:39789|RBW]]}
size 69
[junit4] 2> 818208 WARN (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 818371 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 818371 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 818371 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 818416 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 818416 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 818428 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5361917214936808]
[junit4] 2> 818640 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@71d82c87[control_collection_shard1_replica_n1] main]
[junit4] 2> 818641 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 818642 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 818646 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 818655 INFO
(searcherExecutor-5918-thread-1-processing-n:127.0.0.1:41076__ihd
x:control_collection_shard1_replica_n1 c:control_collection s:shard1)
[n:127.0.0.1:41076__ihd c:control_collection s:shard1
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher
Searcher@71d82c87[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 818655 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1603338031848226816
[junit4] 2> 818665 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/control_collection/terms/shard1 to Terms{values={core_node2=0},
version=0}
[junit4] 2> 818669 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 818669 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 818670 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:41076/_ihd/control_collection_shard1_replica_n1/
[junit4] 2> 818670 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 818670 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:41076/_ihd/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 818670 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 818675 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:41076/_ihd/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 818730 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 818734 INFO (qtp1789081756-107628) [n:127.0.0.1:41076__ihd
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=5076
[junit4] 2> 818744 INFO (qtp1789081756-107633) [n:127.0.0.1:41076__ihd
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 818840 INFO (zkCallback-58873-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 819515 INFO
(OverseerCollectionConfigSetProcessor-73978728883421188-127.0.0.1:41076__ihd-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> 819744 INFO (qtp1789081756-107633) [n:127.0.0.1:41076__ihd
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:41076__ihd&wt=javabin&version=2}
status=0 QTime=6269
[junit4] 2> 819761 INFO (zkConnectionManagerCallback-58892-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 819763 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 819765 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60580/solr ready
[junit4] 2> 819768 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 819770 INFO (qtp1789081756-107629) [n:127.0.0.1:41076__ihd
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 819781 INFO (OverseerThreadFactory-5913-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 819783 WARN (OverseerThreadFactory-5913-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection
(collection1) without cores.
[junit4] 2> 819899 INFO (qtp1789081756-107629) [n:127.0.0.1:41076__ihd
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 819906 INFO
(OverseerCollectionConfigSetProcessor-73978728883421188-127.0.0.1:41076__ihd-n_0000000000)
[ ] 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> 819915 INFO (qtp1789081756-107629) [n:127.0.0.1:41076__ihd
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2}
status=0 QTime=145
[junit4] 2> 820055 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-1-001
of type NRT
[junit4] 2> 820057 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
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> 820058 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 820058 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 820058 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 820059 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@62b51e60{/_ihd,null,AVAILABLE}
[junit4] 2> 820059 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@25e8bff0{HTTP/1.1,[http/1.1]}{127.0.0.1:37876}
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.Server Started @820380ms
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:52795/hdfs__localhost_52795__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001_tempDir-002_jetty1,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/_ihd, hostPort=37876,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-1-001/cores}
[junit4] 2> 820060 ERROR
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 820060 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-15T11:31:42.896Z
[junit4] 2> 820086 INFO (zkConnectionManagerCallback-58894-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 820087 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 820087 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-1-001/solr.xml
[junit4] 2> 820093 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 820093 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 820095 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 820254 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60580/solr
[junit4] 2> 820400 INFO (zkConnectionManagerCallback-58898-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 820402 INFO (zkConnectionManagerCallback-58900-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 820426 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 820429 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 820433 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 4 transient cores
[junit4] 2> 820433 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37876__ihd
[junit4] 2> 820435 INFO (zkCallback-58880-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 820435 INFO (zkCallback-58873-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 820454 INFO (zkCallback-58891-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 820463 INFO (zkCallback-58899-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 820571 INFO (zkConnectionManagerCallback-58907-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 820591 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 820619 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:60580/solr ready
[junit4] 2> 820631 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 820711 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 820808 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 820808 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 820811 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:37876__ihd ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-1-001/cores
[junit4] 2> 820892 INFO (qtp191922601-107698) [n:127.0.0.1:37876__ihd
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:37876__ihd&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 820908 INFO (OverseerThreadFactory-5913-thread-3) [
c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified
127.0.0.1:37876__ihd for creating new replica
[junit4] 2> 820914 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
x:collection1_shard2_replica_n41] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 821943 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 822012 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema
[collection1_shard2_replica_n41] Schema name=test
[junit4] 2> 822160 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 822307 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n41' using configuration from
collection collection1, trusted=true
[junit4] 2> 822307 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n41' (registry
'solr.core.collection1.shard2.replica_n41') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 822309 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:52795/solr_hdfs_home
[junit4] 2> 822309 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 822309 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 822343 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
[[collection1_shard2_replica_n41] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-1-001/cores/collection1_shard2_replica_n41],
dataDir=[hdfs://localhost:52795/solr_hdfs_home/collection1/core_node42/data/]
[junit4] 2> 822345 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/collection1/core_node42/data/snapshot_metadata
[junit4] 2> 822446 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 822446 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 822446 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 822514 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 822516 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/collection1/core_node42/data
[junit4] 2> 822570 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/collection1/core_node42/data/index
[junit4] 2> 822592 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 822592 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 822592 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 822606 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 822607 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=88.4326171875,
floorSegmentMB=1.5029296875, forceMergeDeletesPctAllowed=25.025278258247063,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6050041663921454
[junit4] 2> 822708 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39789 is added to
blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-fb56f893-121b-4cb6-99c7-21247910e34d:NORMAL:127.0.0.1:39789|FINALIZED]]}
size 0
[junit4] 2> 822718 WARN (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 823075 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 823075 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 823075 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 823109 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 823109 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 823131 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5361917214936808]
[junit4] 2> 823150 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2c21085d[collection1_shard2_replica_n41] main]
[junit4] 2> 823151 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 823152 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 823153 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 823162 INFO
(searcherExecutor-5932-thread-1-processing-n:127.0.0.1:37876__ihd
x:collection1_shard2_replica_n41 c:collection1 s:shard2)
[n:127.0.0.1:37876__ihd c:collection1 s:shard2
x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
[collection1_shard2_replica_n41] Registered new searcher
Searcher@2c21085d[collection1_shard2_replica_n41]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 823173 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1603338036585693184
[junit4] 2> 823183 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/collection1/terms/shard2 to
Terms{values={core_node42=0}, version=0}
[junit4] 2> 823187 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 823187 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 823187 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:37876/_ihd/collection1_shard2_replica_n41/
[junit4] 2> 823187 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 823188 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
http://127.0.0.1:37876/_ihd/collection1_shard2_replica_n41/ has no replicas
[junit4] 2> 823188 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 823197 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:37876/_ihd/collection1_shard2_replica_n41/ shard2
[junit4] 2> 823198 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 823201 INFO (qtp191922601-107693) [n:127.0.0.1:37876__ihd
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2287
[junit4] 2> 823207 INFO (qtp191922601-107698) [n:127.0.0.1:37876__ihd
c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={node=127.0.0.1:37876__ihd&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=2315
[junit4] 2> 823382 INFO (zkCallback-58899-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 823584 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-2-001
of type NRT
[junit4] 2> 823586 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
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> 823587 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 823587 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 823587 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 823588 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@b9380b4{/_ihd,null,AVAILABLE}
[junit4] 2> 823588 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@42194062{HTTP/1.1,[http/1.1]}{127.0.0.1:41951}
[junit4] 2> 823588 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.Server Started @823909ms
[junit4] 2> 823588 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:52795/hdfs__localhost_52795__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001_tempDir-002_jetty2,
solrconfig=solrconfig.xml, hostContext=/_ihd, hostPort=41951,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-2-001/cores}
[junit4] 2> 823589 ERROR
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 823598 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 823598 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 823598 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 823598 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 823598 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-15T11:31:46.434Z
[junit4] 2> 823623 INFO (zkConnectionManagerCallback-58910-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 823624 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 823624 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-2-001/solr.xml
[junit4] 2> 823629 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 823629 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 823633 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 823856 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60580/solr
[junit4] 2> 823857 INFO (zkConnectionManagerCallback-58914-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 823863 INFO (zkConnectionManagerCallback-58916-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 823872 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 823874 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 823877 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 4 transient cores
[junit4] 2> 823877 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41951__ihd
[junit4] 2> 823879 INFO (zkCallback-58880-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 823893 INFO (zkCallback-58873-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 823893 INFO (zkCallback-58899-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 823982 INFO (zkCallback-58891-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 824019 INFO (zkCallback-58915-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 824064 INFO (zkCallback-58906-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 824064 INFO (zkConnectionManagerCallback-58923-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 824066 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 824068 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:60580/solr ready
[junit4] 2> 824068 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 824094 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 824108 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 824109 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 824111 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2])
[n:127.0.0.1:41951__ihd ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-2-001/cores
[junit4] 2> 824173 INFO (qtp191922601-107694) [n:127.0.0.1:37876__ihd
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:41951__ihd&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 824196 INFO
(OverseerCollectionConfigSetProcessor-73978728883421188-127.0.0.1:41076__ihd-n_0000000000)
[ ] 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> 824205 INFO (OverseerThreadFactory-5913-thread-4) [
c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified
127.0.0.1:41951__ihd for creating new replica
[junit4] 2> 824213 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
x:collection1_shard1_replica_n43] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 824400 INFO (zkCallback-58899-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 825233 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 825253 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema
[collection1_shard1_replica_n43] Schema name=test
[junit4] 2> 825409 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 825498 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n43' using configuration from
collection collection1, trusted=true
[junit4] 2> 825500 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_n43' (registry
'solr.core.collection1.shard1.replica_n43') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@43044cad
[junit4] 2> 825500 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:52795/solr_hdfs_home
[junit4] 2> 825500 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 825500 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 825500 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore
[[collection1_shard1_replica_n43] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-2-001/cores/collection1_shard1_replica_n43],
dataDir=[hdfs://localhost:52795/solr_hdfs_home/collection1/core_node44/data/]
[junit4] 2> 825502 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/collection1/core_node44/data/snapshot_metadata
[junit4] 2> 825533 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 825533 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 825533 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 825550 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 825555 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/collection1/core_node44/data
[junit4] 2> 825591 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:52795/solr_hdfs_home/collection1/core_node44/data/index
[junit4] 2> 825599 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 825599 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 825599 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 825837 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 825838 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=88.4326171875,
floorSegmentMB=1.5029296875, forceMergeDeletesPctAllowed=25.025278258247063,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6050041663921454
[junit4] 2> 825919 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39789 is added to
blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-85c19281-3627-439f-9fc8-e67f1122b1ad:NORMAL:127.0.0.1:39789|RBW]]}
size 0
[junit4] 2> 825920 WARN (DataStreamer for file
/solr_hdfs_home/collection1/core_node44/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> 825978 WARN (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 826143 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 826143 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 826143 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 826181 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 826181 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 826207 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5361917214936808]
[junit4] 2> 826533 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5b035c33[collection1_shard1_replica_n43] main]
[junit4] 2> 826535 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 826536 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 826537 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 826539 INFO
(searcherExecutor-5946-thread-1-processing-n:127.0.0.1:41951__ihd
x:collection1_shard1_replica_n43 c:collection1 s:shard1)
[n:127.0.0.1:41951__ihd c:collection1 s:shard1
x:collection1_shard1_replica_n43] o.a.s.c.SolrCore
[collection1_shard1_replica_n43] Registered new searcher
Searcher@5b035c33[collection1_shard1_replica_n43]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 826539 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1603338040115200000
[junit4] 2> 826560 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/collection1/terms/shard1 to
Terms{values={core_node44=0}, version=0}
[junit4] 2> 826564 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 826564 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 826564 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:41951/_ihd/collection1_shard1_replica_n43/
[junit4] 2> 826565 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 826565 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy
http://127.0.0.1:41951/_ihd/collection1_shard1_replica_n43/ has no replicas
[junit4] 2> 826565 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 826575 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:41951/_ihd/collection1_shard1_replica_n43/ shard1
[junit4] 2> 826575 INFO (zkCallback-58899-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 826577 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 826593 INFO (qtp1508237977-107742) [n:127.0.0.1:41951__ihd
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2380
[junit4] 2> 826601 INFO (qtp191922601-107694) [n:127.0.0.1:37876__ihd
c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={node=127.0.0.1:41951__ihd&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=2427
[junit4] 2> 826605 INFO
(OverseerCollectionConfigSetProcessor-73978728883421188-127.0.0.1:41076__ihd-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 826680 INFO (zkCallback-58915-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 826680 INFO (zkCallback-58899-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 826836 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-3-001
of type NRT
[junit4] 2> 826837 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
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> 826838 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 826838 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 826838 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 826839 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6e4aa956{/_ihd,null,AVAILABLE}
[junit4] 2> 826839 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7249b892{HTTP/1.1,[http/1.1]}{127.0.0.1:42165}
[junit4] 2> 826840 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.e.j.s.Server Started @827160ms
[junit4] 2> 826840 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:52795/hdfs__localhost_52795__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001_tempDir-002_jetty3,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/_ihd, hostPort=42165,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_96DD454BA974C6D2-001/shard-3-001/cores}
[junit4] 2> 826840 ERROR
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 826856 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 826856 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 826856 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 826856 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 826856 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-06-15T11:31:49.692Z
[junit4] 2> 826869 INFO (zkConnectionManagerCallback-58926-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 826870 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 826870 INFO
(TEST-HdfsBasicDistributedZkTest.test-seed#[96DD454BA974C6D2]) [
[...truncated too long message...]
o not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null398738345
[copy] Copying 39 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null398738345
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null398738345
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1140835165
[copy] Copying 247 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1140835165
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1140835165
check-working-copy:
[ivy:cachepath] :: resolving dependencies ::
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in
public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 61ms :: artifacts dl 11ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for
further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 430 minutes 23 seconds
Archiving artifacts
WARN: No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
WARN: java.lang.InterruptedException: no matches found within 10000
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
