You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/11/24 22:11:17 UTC
[JENKINS] Lucene-Solr-6.x-MacOSX (64bit/jdk1.8.0) - Build # 534 -
Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/534/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.update.processor.TestNamedUpdateProcessors.test
Error Message:
Index: 0, Size: 0
Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
at __randomizedtesting.SeedInfo.seed([BE3075A0AA2B8600:36644A7A04D7EBF8]:0)
at java.util.ArrayList.rangeCheck(ArrayList.java:653)
at java.util.ArrayList.get(ArrayList.java:429)
at org.apache.solr.update.processor.TestNamedUpdateProcessors.test(TestNamedUpdateProcessors.java:128)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11137 lines...]
[junit4] Suite: org.apache.solr.update.processor.TestNamedUpdateProcessors
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/init-core-data-001
[junit4] 2> 663122 INFO (SUITE-TestNamedUpdateProcessors-seed#[BE3075A0AA2B8600]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 663123 INFO (SUITE-TestNamedUpdateProcessors-seed#[BE3075A0AA2B8600]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 663127 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 663129 INFO (Thread-1087) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 663129 INFO (Thread-1087) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 663230 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkTestServer start zk server on port:49692
[junit4] 2> 663374 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 663402 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 663405 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 663408 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 663411 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 663413 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 663416 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 663418 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 663421 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 663424 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 663427 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 664677 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/control-001/cores/collection1
[junit4] 2> 664679 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 664681 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ba917da{/,null,AVAILABLE}
[junit4] 2> 664684 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.ServerConnector Started ServerConnector@79660191{HTTP/1.1,[http/1.1]}{127.0.0.1:49719}
[junit4] 2> 664684 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server Started @673132ms
[junit4] 2> 664684 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/tempDir-001/control/data, hostContext=/, hostPort=49719, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/control-001/cores}
[junit4] 2> 664685 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 664686 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.0
[junit4] 2> 664686 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 664686 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 664686 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-24T21:01:17.603Z
[junit4] 2> 664694 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 664694 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/control-001/solr.xml
[junit4] 2> 664705 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 664706 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49692/solr
[junit4] 2> 664751 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49719_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49719_
[junit4] 2> 664753 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49719_ ] o.a.s.c.Overseer Overseer (id=96994674347474948-127.0.0.1:49719_-n_0000000000) starting
[junit4] 2> 664768 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49719_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49719_
[junit4] 2> 664771 INFO (OverseerStateUpdate-96994674347474948-127.0.0.1:49719_-n_0000000000) [n:127.0.0.1:49719_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 664805 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49719_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/control-001/cores
[junit4] 2> 664805 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49719_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 664810 INFO (OverseerStateUpdate-96994674347474948-127.0.0.1:49719_-n_0000000000) [n:127.0.0.1:49719_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 665825 WARN (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 665825 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 665837 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 665918 WARN (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 665934 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 665956 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 665957 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/control-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/control-001/cores/collection1/data/]
[junit4] 2> 665957 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e11a22
[junit4] 2> 665958 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.708986776409273]
[junit4] 2> 665964 WARN (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 665974 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 665974 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 665975 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 665975 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 665975 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=93.2890625, floorSegmentMB=2.1083984375, forceMergeDeletesPctAllowed=4.4874041668818805, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5880508942363182
[junit4] 2> 665977 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27ceaf7f[collection1] main]
[junit4] 2> 665979 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 665980 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 665980 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 665980 INFO (searcherExecutor-2798-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27ceaf7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 665981 INFO (coreLoadExecutor-2797-thread-1-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551914792541749248
[junit4] 2> 665993 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 665993 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 665993 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49719/collection1/
[junit4] 2> 665993 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 665993 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:49719/collection1/ has no replicas
[junit4] 2> 666002 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49719/collection1/ shard1
[junit4] 2> 666177 INFO (coreZkRegister-2790-thread-1-processing-n:127.0.0.1:49719_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49719_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 666327 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 666329 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49692/solr ready
[junit4] 2> 666329 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 666329 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 666817 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001/cores/collection1
[junit4] 2> 666819 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001
[junit4] 2> 666819 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 666821 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28996e52{/,null,AVAILABLE}
[junit4] 2> 666822 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4cf622b9{HTTP/1.1,[http/1.1]}{127.0.0.1:49731}
[junit4] 2> 666822 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server Started @675269ms
[junit4] 2> 666822 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=49731, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001/cores}
[junit4] 2> 666823 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 666823 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.0
[junit4] 2> 666824 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 666824 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 666824 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-24T21:01:19.741Z
[junit4] 2> 666828 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 666828 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001/solr.xml
[junit4] 2> 666841 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 666842 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49692/solr
[junit4] 2> 666860 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 666869 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49731_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49731_
[junit4] 2> 666872 INFO (zkCallback-1259-thread-2-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666873 INFO (zkCallback-1263-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666875 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666931 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49731_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001/cores
[junit4] 2> 666932 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49731_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 666936 INFO (OverseerStateUpdate-96994674347474948-127.0.0.1:49719_-n_0000000000) [n:127.0.0.1:49719_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 667048 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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> 667950 WARN (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 667951 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 667963 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 668039 WARN (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 668041 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 668061 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 668061 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 668061 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e11a22
[junit4] 2> 668063 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.708986776409273]
[junit4] 2> 668070 WARN (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 668084 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 668084 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 668085 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 668085 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 668086 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=93.2890625, floorSegmentMB=2.1083984375, forceMergeDeletesPctAllowed=4.4874041668818805, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5880508942363182
[junit4] 2> 668088 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d3881e7[collection1] main]
[junit4] 2> 668090 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 668091 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 668091 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 668092 INFO (searcherExecutor-2809-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7d3881e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 668092 INFO (coreLoadExecutor-2808-thread-1-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551914794755293184
[junit4] 2> 668104 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 668104 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 668104 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49731/collection1/
[junit4] 2> 668104 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 668104 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:49731/collection1/ has no replicas
[junit4] 2> 668111 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49731/collection1/ shard2
[junit4] 2> 668225 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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> 668284 INFO (coreZkRegister-2803-thread-1-processing-n:127.0.0.1:49731_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:49731_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 668397 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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> 668729 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001/cores/collection1
[junit4] 2> 668731 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001
[junit4] 2> 668731 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 668733 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5c18d338{/,null,AVAILABLE}
[junit4] 2> 668734 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5f2cfad0{HTTP/1.1,[http/1.1]}{127.0.0.1:49737}
[junit4] 2> 668734 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server Started @677181ms
[junit4] 2> 668734 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=49737, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001/cores}
[junit4] 2> 668735 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 668735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.0
[junit4] 2> 668735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 668735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 668735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-24T21:01:21.652Z
[junit4] 2> 668740 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 668740 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001/solr.xml
[junit4] 2> 668751 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 668752 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49692/solr
[junit4] 2> 668772 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49737_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 668781 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49737_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49737_
[junit4] 2> 668784 INFO (zkCallback-1263-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668785 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668785 INFO (zkCallback-1259-thread-2-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668786 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668840 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49737_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001/cores
[junit4] 2> 668840 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49737_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 668844 INFO (OverseerStateUpdate-96994674347474948-127.0.0.1:49719_-n_0000000000) [n:127.0.0.1:49719_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 668950 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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> 668950 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] 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> 669865 WARN (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 669866 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 669897 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 669976 WARN (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 669978 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 669998 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 669998 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 669998 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e11a22
[junit4] 2> 670000 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.708986776409273]
[junit4] 2> 670006 WARN (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 670018 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 670018 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 670019 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 670019 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 670019 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=93.2890625, floorSegmentMB=2.1083984375, forceMergeDeletesPctAllowed=4.4874041668818805, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5880508942363182
[junit4] 2> 670021 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c190277[collection1] main]
[junit4] 2> 670023 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 670023 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 670023 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 670024 INFO (searcherExecutor-2820-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c190277[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 670024 INFO (coreLoadExecutor-2819-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551914796781142016
[junit4] 2> 670035 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 670035 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 670035 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49737/collection1/
[junit4] 2> 670035 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 670036 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:49737/collection1/ has no replicas
[junit4] 2> 670044 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49737/collection1/ shard1
[junit4] 2> 670160 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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> 670160 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] 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> 670204 INFO (coreZkRegister-2814-thread-1-processing-n:127.0.0.1:49737_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:49737_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 670322 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] 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> 670322 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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> 670671 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001/cores/collection1
[junit4] 2> 670672 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001
[junit4] 2> 670673 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 670675 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3a5bb081{/,null,AVAILABLE}
[junit4] 2> 670675 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3c7dd24f{HTTP/1.1,[http/1.1]}{127.0.0.1:49744}
[junit4] 2> 670675 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server Started @679123ms
[junit4] 2> 670675 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/, hostPort=49744, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001/cores}
[junit4] 2> 670676 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 670677 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.0
[junit4] 2> 670678 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 670678 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 670678 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-24T21:01:23.595Z
[junit4] 2> 670682 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 670682 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001/solr.xml
[junit4] 2> 670766 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 670766 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49692/solr
[junit4] 2> 670784 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49744_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 670794 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49744_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49744_
[junit4] 2> 670799 INFO (zkCallback-1263-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 670799 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 670800 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 670802 INFO (zkCallback-1281-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 670802 INFO (zkCallback-1259-thread-2-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 670850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49744_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001/cores
[junit4] 2> 670850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49744_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 670854 INFO (OverseerStateUpdate-96994674347474948-127.0.0.1:49719_-n_0000000000) [n:127.0.0.1:49719_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 670958 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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: [4])
[junit4] 2> 670958 INFO (zkCallback-1281-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ ] 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: [4])
[junit4] 2> 670958 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] 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: [4])
[junit4] 2> 671873 WARN (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 671874 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 671885 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 671969 WARN (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 671971 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 671993 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 671993 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 671994 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e11a22
[junit4] 2> 671996 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.708986776409273]
[junit4] 2> 672002 WARN (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 672014 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 672014 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 672015 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 672016 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 672016 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=93.2890625, floorSegmentMB=2.1083984375, forceMergeDeletesPctAllowed=4.4874041668818805, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5880508942363182
[junit4] 2> 672018 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ff9186a[collection1] main]
[junit4] 2> 672020 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 672020 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 672020 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 672021 INFO (searcherExecutor-2831-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ff9186a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 672021 INFO (coreLoadExecutor-2830-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551914798875148288
[junit4] 2> 672028 INFO (coreZkRegister-2825-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 672028 INFO (updateExecutor-1278-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 672029 INFO (recoveryExecutor-1279-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 672030 INFO (recoveryExecutor-1279-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 672030 INFO (recoveryExecutor-1279-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 672030 INFO (recoveryExecutor-1279-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 672030 INFO (recoveryExecutor-1279-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:49731/collection1/] and I am [http://127.0.0.1:49744/collection1/]
[junit4] 2> 672033 INFO (recoveryExecutor-1279-thread-1-processing-n:127.0.0.1:49744_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49744_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:49731]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:49744_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 672037 INFO (qtp359384313-5861) [n:127.0.0.1:49731_ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 672038 INFO (qtp359384313-5861) [n:127.0.0.1:49731_ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard2 of collection1) have state: recovering
[junit4] 2> 672038 INFO (qtp359384313-5861) [n:127.0.0.1:49731_ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:49744_, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:49744","node_name":"127.0.0.1:49744_","state":"down"}
[junit4] 2> 672144 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] 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: [4])
[junit4] 2> 672144 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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: [4])
[junit4] 2> 672144 INFO (zkCallback-1281-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ ] 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: [4])
[junit4] 2> 672728 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001/cores/collection1
[junit4] 2> 672730 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001
[junit4] 2> 672730 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 672733 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@229ae1d3{/,null,AVAILABLE}
[junit4] 2> 672733 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.ServerConnector Started ServerConnector@25e0bf65{HTTP/1.1,[http/1.1]}{127.0.0.1:49749}
[junit4] 2> 672733 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.e.j.s.Server Started @681180ms
[junit4] 2> 672733 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=49749, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001/cores}
[junit4] 2> 672734 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 672734 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.0
[junit4] 2> 672735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 672735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 672735 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-24T21:01:25.652Z
[junit4] 2> 672739 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 672739 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001/solr.xml
[junit4] 2> 672798 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 672798 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49692/solr
[junit4] 2> 672817 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49749_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 672826 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49749_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49749_
[junit4] 2> 672830 INFO (zkCallback-1263-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 672830 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 672831 INFO (zkCallback-1281-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 672831 INFO (zkCallback-1259-thread-2-processing-n:127.0.0.1:49719_) [n:127.0.0.1:49719_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 672832 INFO (zkCallback-1288-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 672832 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 672902 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49749_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001/cores
[junit4] 2> 672902 INFO (TEST-TestNamedUpdateProcessors.test-seed#[BE3075A0AA2B8600]) [n:127.0.0.1:49749_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 672907 INFO (OverseerStateUpdate-96994674347474948-127.0.0.1:49719_-n_0000000000) [n:127.0.0.1:49719_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 673023 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:49737_) [n:127.0.0.1:49737_ ] 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: [5])
[junit4] 2> 673023 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] 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: [5])
[junit4] 2> 673023 INFO (zkCallback-1288-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ ] 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: [5])
[junit4] 2> 673023 INFO (zkCallback-1281-thread-1-processing-n:127.0.0.1:49744_) [n:127.0.0.1:49744_ ] 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: [5])
[junit4] 2> 673044 INFO (qtp359384313-5861) [n:127.0.0.1:49731_ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:49744_, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:49744","node_name":"127.0.0.1:49744_","state":"recovering"}
[junit4] 2> 673044 INFO (qtp359384313-5861) [n:127.0.0.1:49731_ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 673044 INFO (qtp359384313-5861) [n:127.0.0.1:49731_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:49744_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1007
[junit4] 2> 673921 WARN (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 673921 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 673933 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 674017 WARN (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 674018 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 674038 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 674039 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TestNamedUpdateProcessors_BE3075A0AA2B8600-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 674039 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e11a22
[junit4] 2> 674041 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.708986776409273]
[junit4] 2> 674046 WARN (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 674061 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 674061 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 674062 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 674062 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 674062 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=93.2890625, floorSegmentMB=2.1083984375, forceMergeDeletesPctAllowed=4.4874041668818805, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5880508942363182
[junit4] 2> 674064 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@250b8d5d[collection1] main]
[junit4] 2> 674066 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 674067 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 674067 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 674068 INFO (searcherExecutor-2842-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@250b8d5d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 674068 INFO (coreLoadExecutor-2841-thread-1-processing-n:127.0.0.1:49749_) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551914801021583360
[junit4] 2> 674074 INFO (coreZkRegister-2836-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 674074 INFO (updateExecutor-1285-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 674075 INFO (recoveryExecutor-1286-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 674076 INFO (recoveryExecutor-1286-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 674076 INFO (recoveryExecutor-1286-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 674076 INFO (recoveryExecutor-1286-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 674076 INFO (recoveryExecutor-1286-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:49737/collection1/] and I am [http://127.0.0.1:49749/collection1/]
[junit4] 2> 674078 INFO (recoveryExecutor-1286-thread-1-processing-n:127.0.0.1:49749_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:49749_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:49737]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:49749_&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 674082 INFO (qtp542791928-5890) [n:127.0.0.1:49737_ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 674083 INFO (qtp542791928-5890) [n:127.0.0.1:49737_ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering
[junit4] 2> 674083 INFO (qtp542791928-5890) [n:127.0.0.1:49737_ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:49749_, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{"core":"collection1","base_url":"http://127.0.0.1:49749","node_name":"127.0.0.1:49749_","state":"down"}
[junit4] 2> 674192 INFO (zkCallback-1269-thread-2-processing-n:127.0.0.1:49731_) [n:127.0.0.1:49731_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/s
[...truncated too long message...]
WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/client/solrj/response/QueryResponseTest.java (at line 103)
[ecj-lint] InputStream is = new SolrResourceLoader().openResource("solrj/sampleGroupResponse.xml");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 41. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/client/solrj/response/QueryResponseTest.java (at line 205)
[ecj-lint] InputStream is = new SolrResourceLoader().openResource("solrj/sampleSimpleGroupResponse.xml");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 42. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/client/solrj/response/TestClusteringResponse.java (at line 41)
[ecj-lint] InputStream is = new SolrResourceLoader().openResource("solrj/sampleClusteringResponse.xml");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 43. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/ContentStreamTest.java (at line 48)
[ecj-lint] InputStream is = new SolrResourceLoader().openResource( "solrj/README" );
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 44. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/ContentStreamTest.java (at line 77)
[ecj-lint] InputStream is = new SolrResourceLoader().openResource( "solrj/README" );
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 45. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 59)
[ecj-lint] new JavaBinCodec().marshal(s, os);
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 46. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 61)
[ecj-lint] Object o = new JavaBinCodec().unmarshal(is);
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 47. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 168)
[ecj-lint] JavaBinCodec javabin = new JavaBinCodec(){
[ecj-lint] ^^^^^^^
[ecj-lint] Resource leak: 'javabin' is never closed
[ecj-lint] ----------
[ecj-lint] 48. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 210)
[ecj-lint] JavaBinCodec javabin = new JavaBinCodec(){
[ecj-lint] ^^^^^^^
[ecj-lint] Resource leak: 'javabin' is never closed
[ecj-lint] ----------
[ecj-lint] 49. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 228)
[ecj-lint] JavaBinCodec javabin = new JavaBinCodec();
[ecj-lint] ^^^^^^^
[ecj-lint] Resource leak: 'javabin' is never closed
[ecj-lint] ----------
[ecj-lint] 50. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 251)
[ecj-lint] JavaBinCodec javabin = new JavaBinCodec();
[ecj-lint] ^^^^^^^
[ecj-lint] Resource leak: 'javabin' is never closed
[ecj-lint] ----------
[ecj-lint] 51. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 286)
[ecj-lint] JavaBinCodec javabin = new JavaBinCodec();
[ecj-lint] ^^^^^^^
[ecj-lint] Resource leak: 'javabin' is never closed
[ecj-lint] ----------
[ecj-lint] 52. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 293)
[ecj-lint] return new JavaBinCodec().unmarshal(new ByteArrayInputStream(bytes));
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 53. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 346)
[ecj-lint] m1 = (Map) new JavaBinCodec(null, stringCache).unmarshal(new ByteArrayInputStream(b1));
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 54. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 347)
[ecj-lint] m2 = (Map) new JavaBinCodec(null, stringCache).unmarshal(new ByteArrayInputStream(b2));
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 55. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestJavaBinCodec.java (at line 559)
[ecj-lint] JavaBinCodec javabin = new JavaBinCodec(null, stringCache);
[ecj-lint] ^^^^^^^
[ecj-lint] Resource leak: 'javabin' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 56. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 78)
[ecj-lint] new JavaBinCodec(null).marshal(nl,baos);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 57. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 80)
[ecj-lint] nl = (NamedList) new JavaBinCodec().unmarshal(new ByteArrayInputStream(arr));
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 58. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 118)
[ecj-lint] new JavaBinCodec(null).marshal(nl,baos);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 59. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 120)
[ecj-lint] nl = (NamedList) new JavaBinCodec().unmarshal(new ByteArrayInputStream(arr));
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 60. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 140)
[ecj-lint] new JavaBinCodec(null).marshal(r,baos);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 61. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 144)
[ecj-lint] NamedList result = (NamedList) new JavaBinCodec().unmarshal(new ByteArrayInputStream(arr));
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 62. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 251)
[ecj-lint] new JavaBinCodec(null).marshal(nl,baos);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 63. WARNING in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/solrj/src/test/org/apache/solr/common/util/TestNamedListCodec.java (at line 254)
[ecj-lint] res = (NamedList) new JavaBinCodec().unmarshal(new ByteArrayInputStream(arr));
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 63 problems (1 error, 62 warnings)
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:765: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:101: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build.xml:671: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/lucene/common-build.xml:1998: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/lucene/common-build.xml:2031: Compile failed; see the compiler error output for details.
Total time: 107 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-6.x-MacOSX (64bit/jdk1.8.0) - Build # 535 -
Still unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/535/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
ObjectTracker found 5 object(s) that were not released!!! [MDCAwareThreadPoolExecutor, TransactionLog, MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:798) at org.apache.solr.core.SolrCore.reload(SolrCore.java:590) at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:944) at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2714) at org.apache.solr.handler.SolrConfigHandler$Command.lambda$handleGET$0(SolrConfigHandler.java:223) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:188) at org.apache.solr.update.UpdateLog.newTransactionLog(UpdateLog.java:344) at org.apache.solr.update.UpdateLog.ensureLog(UpdateLog.java:859) at org.apache.solr.update.UpdateLog.add(UpdateLog.java:428) at org.apache.solr.update.UpdateLog.add(UpdateLog.java:415) at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:299) at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:211) at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:166) at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.AddSchemaFieldsUpdateProcessorFactory$AddSchemaFieldsUpdateProcessor.processAdd(AddSchemaFieldsUpdateProcessorFactory.java:335) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.FieldNameMutatingUpdateProcessorFactory$1.processAdd(FieldNameMutatingUpdateProcessorFactory.java:74) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118) at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48) at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:957) at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1112) at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:738) at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103) at org.apache.solr.handler.loader.JavabinLoader$1.update(JavabinLoader.java:97) at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:179) at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:135) at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:306) at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:251) at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:121) at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:271) at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:251) at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:173) at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:186) at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:107) at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:54) at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2210) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:518) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:66) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:673) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:847) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779) at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88) at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377) at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365) at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152) at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:518) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:332) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:641) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:847) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779) at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88) at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377) at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365) at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152) at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:518) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) at java.lang.Thread.run(Thread.java:745) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:434) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:841) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779) at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88) at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377) at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365) at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152) at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:518) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 5 object(s) that were not released!!! [MDCAwareThreadPoolExecutor, TransactionLog, MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:798)
at org.apache.solr.core.SolrCore.reload(SolrCore.java:590)
at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:944)
at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2714)
at org.apache.solr.handler.SolrConfigHandler$Command.lambda$handleGET$0(SolrConfigHandler.java:223)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:188)
at org.apache.solr.update.UpdateLog.newTransactionLog(UpdateLog.java:344)
at org.apache.solr.update.UpdateLog.ensureLog(UpdateLog.java:859)
at org.apache.solr.update.UpdateLog.add(UpdateLog.java:428)
at org.apache.solr.update.UpdateLog.add(UpdateLog.java:415)
at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:299)
at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:211)
at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:166)
at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.AddSchemaFieldsUpdateProcessorFactory$AddSchemaFieldsUpdateProcessor.processAdd(AddSchemaFieldsUpdateProcessorFactory.java:335)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.FieldNameMutatingUpdateProcessorFactory$1.processAdd(FieldNameMutatingUpdateProcessorFactory.java:74)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.FieldMutatingUpdateProcessor.processAdd(FieldMutatingUpdateProcessor.java:118)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:957)
at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1112)
at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:738)
at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
at org.apache.solr.handler.loader.JavabinLoader$1.update(JavabinLoader.java:97)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:179)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:135)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:306)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:251)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:121)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:271)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:251)
at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:173)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:186)
at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:107)
at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:54)
at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2210)
at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
at org.eclipse.jetty.server.Server.handle(Server.java:518)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:66)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:673)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:847)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779)
at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377)
at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365)
at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
at org.eclipse.jetty.server.Server.handle(Server.java:518)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:332)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:641)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:847)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779)
at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377)
at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365)
at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
at org.eclipse.jetty.server.Server.handle(Server.java:518)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:434)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:841)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779)
at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377)
at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365)
at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
at org.eclipse.jetty.server.Server.handle(Server.java:518)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([85EB4E22669FE854]: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:260)
at sun.reflect.GeneratedMethodAccessor24.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:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.util.TestSolrCLIRunExample: 1) Thread[id=2793, name=searcherExecutor-1403-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.util.TestSolrCLIRunExample:
1) Thread[id=2793, name=searcherExecutor-1403-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([85EB4E22669FE854]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=2793, name=searcherExecutor-1403-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=2793, name=searcherExecutor-1403-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([85EB4E22669FE854]:0)
Build Log:
[...truncated 10870 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/init-core-data-001
[junit4] 2> 315072 INFO (SUITE-TestSolrCLIRunExample-seed#[85EB4E22669FE854]-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) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 315075 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSchemalessExample
[junit4] 2> 315075 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.a.s.u.TestSolrCLIRunExample Selected port 49901 to start schemaless example Solr instance on ...
[junit4] 2> 315197 INFO (Thread-610) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 315199 INFO (Thread-610) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1866eba8{/solr,null,AVAILABLE}
[junit4] 2> 315202 INFO (Thread-610) [ ] o.e.j.s.ServerConnector Started ServerConnector@66fc1e7a{HTTP/1.1,[http/1.1]}{127.0.0.1:49901}
[junit4] 2> 315203 INFO (Thread-610) [ ] o.e.j.s.Server Started @321218ms
[junit4] 2> 315203 INFO (Thread-610) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49901}
[junit4] 2> 315203 ERROR (Thread-610) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 315203 INFO (Thread-610) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 315203 INFO (Thread-610) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port 49901
[junit4] 2> 315203 INFO (Thread-610) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 315203 INFO (Thread-610) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-25T07:08:03.890Z
[junit4] 2> 315204 INFO (Thread-610) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/solr.xml
[junit4] 2> 315215 INFO (Thread-610) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 315221 INFO (Thread-610) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr
[junit4] 2> 317478 INFO (qtp484010357-2544) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=253
[junit4] 2> 317490 INFO (qtp484010357-2545) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 317530 INFO (qtp484010357-2543) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=28
[junit4] 2> 317565 INFO (qtp484010357-2546) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=25
[junit4] 2> 317572 INFO (qtp484010357-2542) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 317837 INFO (qtp484010357-2543) [ ] o.a.s.h.a.CoreAdminOperation core create command name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json
[junit4] 2> 317853 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/extraction/lib
[junit4] 2> 317854 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 317854 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/clustering/lib
[junit4] 2> 317854 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 317855 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/langid/lib
[junit4] 2> 317855 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 317856 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/velocity/lib
[junit4] 2> 317856 WARN (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 317863 INFO (qtp484010357-2543) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 317892 INFO (qtp484010357-2543) [ ] o.a.s.s.IndexSchema [gettingstarted] Schema name=example-data-driven-schema
[junit4] 2> 318047 INFO (qtp484010357-2543) [ ] o.a.s.s.IndexSchema Loaded schema example-data-driven-schema/1.6 with uniqueid field id
[junit4] 2> 318079 INFO (qtp484010357-2543) [ ] o.a.s.c.CoreContainer Creating SolrCore 'gettingstarted' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted
[junit4] 2> 318080 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/data/]
[junit4] 2> 318080 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4013898c
[junit4] 2> 318102 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 318948 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 318948 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 318950 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 318950 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 318955 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bc8cfb7[gettingstarted] main]
[junit4] 2> 318955 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/conf
[junit4] 2> 318956 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 318956 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 318957 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-001/schemaless/solr/gettingstarted/conf/elevate.xml
[junit4] 2> 318972 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 318972 INFO (searcherExecutor-1351-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@3bc8cfb7[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 318972 INFO (searcherExecutor-1351-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 318972 INFO (searcherExecutor-1351-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 318973 INFO (searcherExecutor-1351-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Registered new searcher Searcher@3bc8cfb7[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 318975 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551952969897869312
[junit4] 2> 318978 INFO (qtp484010357-2543) [ x:gettingstarted] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json} status=0 QTime=1141
[junit4] 2> 318980 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@66fc1e7a{HTTP/1.1,[http/1.1]}{127.0.0.1:49901}
[junit4] 2> 318980 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1166147819
[junit4] 2> 318982 INFO (coreCloseExecutor-1356-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] CLOSING SolrCore org.apache.solr.core.SolrCore@5c9cd93a
[junit4] 2> 319043 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1866eba8{/solr,null,UNAVAILABLE}
[junit4] 2> 319044 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.a.s.u.TestSolrCLIRunExample Stopped standalone Solr instance running on port 49901
[junit4] 2> 319044 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[85EB4E22669FE854]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemalessExample
[junit4] 2> 319047 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 319085 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003
[junit4] 2> 319085 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 319085 INFO (Thread-620) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 319085 INFO (Thread-620) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 319185 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.ZkTestServer start zk server on port:49915
[junit4] 2> 319217 INFO (jetty-launcher-532-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 319219 INFO (jetty-launcher-532-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e808b90{/solr,null,AVAILABLE}
[junit4] 2> 319220 INFO (jetty-launcher-532-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@3a257bfc{HTTP/1.1,[http/1.1]}{127.0.0.1:49912}
[junit4] 2> 319220 INFO (jetty-launcher-532-thread-1) [ ] o.e.j.s.Server Started @325235ms
[junit4] 2> 319220 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49912}
[junit4] 2> 319220 ERROR (jetty-launcher-532-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 319221 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 319221 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port 49912
[junit4] 2> 319221 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 319221 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-25T07:08:07.908Z
[junit4] 2> 319226 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 319239 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 319239 INFO (jetty-launcher-532-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49915/solr
[junit4] 2> 319296 INFO (jetty-launcher-532-thread-1) [n:localhost:49912_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:49912_solr
[junit4] 2> 319299 INFO (jetty-launcher-532-thread-1) [n:localhost:49912_solr ] o.a.s.c.Overseer Overseer (id=96997060626087939-localhost:49912_solr-n_0000000000) starting
[junit4] 2> 319316 INFO (jetty-launcher-532-thread-1) [n:localhost:49912_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:49912_solr
[junit4] 2> 319319 INFO (zkCallback-538-thread-1-processing-n:localhost:49912_solr) [n:localhost:49912_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 319430 INFO (jetty-launcher-532-thread-1) [n:localhost:49912_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1
[junit4] 2> 319495 INFO (qtp571746624-2569) [n:localhost:49912_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=50
[junit4] 2> 319500 INFO (qtp571746624-2570) [n:localhost:49912_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 319508 INFO (qtp571746624-2570) [n:localhost:49912_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=8
[junit4] 2> 319516 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 319519 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49915/solr ready
[junit4] 2> 319523 INFO (qtp571746624-2573) [n:localhost:49912_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 319523 INFO (qtp571746624-2573) [n:localhost:49912_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 319554 INFO (qtp571746624-2570) [n:localhost:49912_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=27
[junit4] 2> 319560 INFO (qtp571746624-2572) [n:localhost:49912_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 319563 INFO (qtp571746624-2572) [n:localhost:49912_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=2
[junit4] 2> 319569 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 319571 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[85EB4E22669FE854]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49915/solr ready
[junit4] 2> 319767 INFO (qtp571746624-2569) [n:localhost:49912_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 319767 INFO (qtp571746624-2569) [n:localhost:49912_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 319769 INFO (qtp571746624-2571) [n:localhost:49912_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
[junit4] 2> 319774 INFO (OverseerThreadFactory-1363-thread-1-processing-n:localhost:49912_solr) [n:localhost:49912_solr ] o.a.s.c.CreateCollectionCmd Create collection testCloudExamplePrompt
[junit4] 2> 319774 WARN (OverseerThreadFactory-1363-thread-1-processing-n:localhost:49912_solr) [n:localhost:49912_solr ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 319896 INFO (qtp571746624-2569) [n:localhost:49912_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 319896 INFO (qtp571746624-2572) [n:localhost:49912_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 319896 INFO (qtp571746624-2573) [n:localhost:49912_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 319897 INFO (qtp571746624-2567) [n:localhost:49912_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 320019 INFO (zkCallback-538-thread-1-processing-n:localhost:49912_solr) [n:localhost:49912_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 320927 INFO (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 320927 INFO (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 320928 INFO (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 320928 INFO (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 320928 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/extraction/lib
[junit4] 2> 320930 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 320930 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/extraction/lib
[junit4] 2> 320931 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/clustering/lib
[junit4] 2> 320931 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 320931 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 320932 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/langid/lib
[junit4] 2> 320928 INFO (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 320933 INFO (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 320935 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/extraction/lib
[junit4] 2> 320935 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 320935 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 320932 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/clustering/lib
[junit4] 2> 320936 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/clustering/lib
[junit4] 2> 320936 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 320937 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/velocity/lib
[junit4] 2> 320937 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 320937 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/langid/lib
[junit4] 2> 320937 WARN (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 320937 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 320938 INFO (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 320938 INFO (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 320939 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/velocity/lib
[junit4] 2> 320939 WARN (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 320939 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/langid/lib
[junit4] 2> 320939 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 320940 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/velocity/lib
[junit4] 2> 320940 WARN (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 320945 INFO (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 320945 INFO (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 320947 INFO (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 320949 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/extraction/lib
[junit4] 2> 320950 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 320951 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/clustering/lib
[junit4] 2> 320951 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 320952 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/langid/lib
[junit4] 2> 320952 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 320953 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/velocity/lib
[junit4] 2> 320953 WARN (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_85EB4E22669FE854-001/tempDir-003/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 320960 INFO (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 321370 INFO (qtp571746624-2572) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica2] Schema name=example-data-driven-schema
[junit4] 2> 321373 INFO (qtp571746624-2573) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica2] Schema name=example-data-driven-schema
[junit4] 2> 321373 INFO (qtp571746624-2569) [n:localhost:49912_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica1] Schema name=example-data-driven-schema
[junit4] 2> 321370 INFO (qtp571746624-2567) [n:localhost:49912_solr c:testCloudExample
[...truncated too long message...]
solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779)
[junit4] > at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
[junit4] > at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377)
[junit4] > at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365)
[junit4] > at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] > at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:434)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:841)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:775)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:779)
[junit4] > at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
[junit4] > at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:377)
[junit4] > at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:365)
[junit4] > at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:156)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:664)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:445)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] > at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([85EB4E22669FE854]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:260)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.util.TestSolrCLIRunExample:
[junit4] > 1) Thread[id=2793, name=searcherExecutor-1403-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([85EB4E22669FE854]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=2793, name=searcherExecutor-1403-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([85EB4E22669FE854]:0)
[junit4] Completed [73/657 (1!)] on J1 in 193.74s, 3 tests, 1 failure, 2 errors, 1 skipped <<< FAILURES!
[...truncated 62640 lines...]