[JENKINS] Lucene-Solr-NightlyTests-master - Build # 1746 - Still Failing

classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|

[JENKINS] Lucene-Solr-NightlyTests-master - Build # 1746 - Still Failing

Apache Jenkins Server-2
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1746/

1 tests failed.
FAILED:  org.apache.solr.TestDistributedSearch.test

Error Message:


Stack Trace:
java.lang.NullPointerException
        at __randomizedtesting.SeedInfo.seed([362C6D8DDAF5E9A:8B36F90273533362]:0)
        at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:596)
        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:1750)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1096)
        at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1042)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13700 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/init-core-data-001
   [junit4]   2> 520398 WARN  (SUITE-TestDistributedSearch-seed#[362C6D8DDAF5E9A]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=65 numCloses=65
   [junit4]   2> 520398 INFO  (SUITE-TestDistributedSearch-seed#[362C6D8DDAF5E9A]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 520401 INFO  (SUITE-TestDistributedSearch-seed#[362C6D8DDAF5E9A]-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-9061)
   [junit4]   2> 520401 INFO  (SUITE-TestDistributedSearch-seed#[362C6D8DDAF5E9A]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 520401 INFO  (SUITE-TestDistributedSearch-seed#[362C6D8DDAF5E9A]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 520587 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/control/cores/collection1
   [junit4]   2> 520589 WARN  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 520589 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 520589 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 520589 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 520599 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 520599 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 520599 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 520600 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2392f619{/,null,AVAILABLE}
   [junit4]   2> 520601 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@25c4b8b3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:41424}
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.Server Started @520702ms
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=41424, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/control/cores}
   [junit4]   2> 520602 ERROR (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 520602 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-01-09T09:59:27.442Z
   [junit4]   2> 520603 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/control/solr.xml
   [junit4]   2> 520610 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 520610 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 520613 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 520744 WARN  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6baa69d0[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 520749 WARN  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2f1a39c5[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 520781 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 520781 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 520809 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 520823 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 520823 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 520825 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/control/cores
   [junit4]   2> 520825 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 520826 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 520875 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 520902 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 521038 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 521049 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 521049 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 521049 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 521051 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.759765625, floorSegmentMB=1.2939453125, forceMergeDeletesPctAllowed=0.7379600603557568, segmentsPerTier=18.0, maxCFSSegmentSizeMB=1.693359375, noCFSRatio=0.2973747530521602, deletesPctAllowed=40.87297584024827
   [junit4]   2> 521138 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 521138 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 521140 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 521141 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 521142 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12375418416298666]
   [junit4]   2> 521142 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14e5ddc8[collection1] main]
   [junit4]   2> 521143 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 521152 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 521199 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 521199 INFO  (coreLoadExecutor-1547-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1622176398614462464
   [junit4]   2> 521201 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 521202 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 521202 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 521202 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 521202 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 521202 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 521203 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 521203 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 521204 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 521206 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14e5ddc8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 521872 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 521873 WARN  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 521873 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 521873 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 521873 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 521874 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 521875 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 521875 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 521875 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c6eb769{/,null,AVAILABLE}
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@2cb4b2f5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:35414}
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.s.Server Started @521976ms
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=35414, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/shard0/cores}
   [junit4]   2> 521876 ERROR (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 521876 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-01-09T09:59:28.716Z
   [junit4]   2> 521877 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 521882 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 521882 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 521883 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 522071 WARN  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6cd53c0c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 522081 WARN  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@363e7752[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 522109 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 522109 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 522135 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 522151 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 522151 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 522154 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/shard0/cores
   [junit4]   2> 522154 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 522155 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 522204 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 522229 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 522367 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 522377 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 522377 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@21fab7a3
   [junit4]   2> 522378 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 522380 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.759765625, floorSegmentMB=1.2939453125, forceMergeDeletesPctAllowed=0.7379600603557568, segmentsPerTier=18.0, maxCFSSegmentSizeMB=1.693359375, noCFSRatio=0.2973747530521602, deletesPctAllowed=40.87297584024827
   [junit4]   2> 522477 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 522477 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 522480 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 522480 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 522481 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12375418416298666]
   [junit4]   2> 522482 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@23fe232b[collection1] main]
   [junit4]   2> 522483 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 522493 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 522531 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 522531 INFO  (coreLoadExecutor-1561-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1622176400011165696
   [junit4]   2> 522533 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 522534 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 522534 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 522534 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 522534 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 522535 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 522535 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 522535 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 522536 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 522538 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 522540 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23fe232b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 522555 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1622176400021651456)} 0 14
   [junit4]   2> 522567 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1622176400038428672)} 0 10
   [junit4]   2> 522595 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1 (1622176400051011584)]} 0 27
   [junit4]   2> 522601 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1 (1622176400079323136)]} 0 4
   [junit4]   2> 522603 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[2 (1622176400084566016)]} 0 1
   [junit4]   2> 522611 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[2 (1622176400094003200)]} 0 1
   [junit4]   2> 522613 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[3 (1622176400096100352)]} 0 0
   [junit4]   2> 522614 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[3 (1622176400097148928)]} 0 1
   [junit4]   2> 522616 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[4 (1622176400099246080)]} 0 1
   [junit4]   2> 522618 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[4 (1622176400100294656)]} 0 1
   [junit4]   2> 522619 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[5 (1622176400102391808)]} 0 0
   [junit4]   2> 522620 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[5 (1622176400103440384)]} 0 0
   [junit4]   2> 522632 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[6 (1622176400105537536)]} 0 11
   [junit4]   2> 522634 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[6 (1622176400118120448)]} 0 0
   [junit4]   2> 522635 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[7 (1622176400120217600)]} 0 0
   [junit4]   2> 522637 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[7 (1622176400121266176)]} 0 0
   [junit4]   2> 522638 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[8 (1622176400122314752)]} 0 1
   [junit4]   2> 522640 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[8 (1622176400124411904)]} 0 1
   [junit4]   2> 522641 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[9 (1622176400125460480)]} 0 0
   [junit4]   2> 522642 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[9 (1622176400127557632)]} 0 0
   [junit4]   2> 522643 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1622176400128606208,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 522643 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2e67ffd2 commitCommandVersion:1622176400128606208
   [junit4]   2> 522666 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74cdcf56[collection1] main]
   [junit4]   2> 522669 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74cdcf56[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c9:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1547027969498}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 522669 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 522669 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 26
   [junit4]   2> 522670 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1622176400156917760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 522670 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@116b6b69 commitCommandVersion:1622176400156917760
   [junit4]   2> 522682 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e78b7ba[collection1] main]
   [junit4]   2> 522685 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e78b7ba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c9:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1547027969517}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 522685 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 522685 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15
   [junit4]   2> 522690 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[10 (1622176400173694976)]} 0 4
   [junit4]   2> 522692 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[10 (1622176400178937856)]} 0 1
   [junit4]   2> 522696 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[11 (1622176400181035008)]} 0 2
   [junit4]   2> 522699 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[11 (1622176400184180736)]} 0 2
   [junit4]   2> 522700 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[12 (1622176400188375040)]} 0 0
   [junit4]   2> 522701 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[12 (1622176400189423616)]} 0 0
   [junit4]   2> 522702 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[13 (1622176400190472192)]} 0 0
   [junit4]   2> 522703 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[13 (1622176400191520768)]} 0 0
   [junit4]   2> 522705 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1622176400192569344)]} 0 1
   [junit4]   2> 522707 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1622176400194666496)]} 0 1
   [junit4]   2> 522708 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[14 (1622176400196763648)]} 0 0
   [junit4]   2> 522709 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[14 (1622176400197812224)]} 0 0
   [junit4]   2> 522710 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[15 (1622176400198860800)]} 0 0
   [junit4]   2> 522711 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[15 (1622176400199909376)]} 0 0
   [junit4]   2> 522712 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[16 (1622176400200957952)]} 0 0
   [junit4]   2> 522713 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[16 (1622176400202006528)]} 0 0
   [junit4]   2> 522715 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[17 (1622176400203055104)]} 0 0
   [junit4]   2> 522716 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[17 (1622176400204103680)]} 0 0
   [junit4]   2> 522717 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[100 (1622176400206200832)]} 0 0
   [junit4]   2> 522718 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[100 (1622176400206200832)]} 0 0
   [junit4]   2> 522719 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[101 (1622176400207249408)]} 0 0
   [junit4]   2> 522720 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[101 (1622176400208297984)]} 0 0
   [junit4]   2> 522721 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[102 (1622176400209346560)]} 0 0
   [junit4]   2> 522722 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[102 (1622176400210395136)]} 0 0
   [junit4]   2> 522723 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[103 (1622176400211443712)]} 0 0
   [junit4]   2> 522724 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[103 (1622176400212492288)]} 0 0
   [junit4]   2> 522725 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[104 (1622176400213540864)]} 0 0
   [junit4]   2> 522726 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[104 (1622176400214589440)]} 0 0
   [junit4]   2> 522727 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[105 (1622176400215638016)]} 0 0
   [junit4]   2> 522728 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[105 (1622176400216686592)]} 0 0
   [junit4]   2> 522729 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[106 (1622176400217735168)]} 0 0
   [junit4]   2> 522730 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[106 (1622176400218783744)]} 0 0
   [junit4]   2> 522731 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[107 (1622176400220880896)]} 0 0
   [junit4]   2> 522732 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[107 (1622176400221929472)]} 0 0
   [junit4]   2> 522733 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[108 (1622176400222978048)]} 0 0
   [junit4]   2> 522734 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[108 (1622176400224026624)]} 0 0
   [junit4]   2> 522735 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[109 (1622176400225075200)]} 0 0
   [junit4]   2> 522736 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[109 (1622176400226123776)]} 0 0
   [junit4]   2> 522737 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[110 (1622176400227172352)]} 0 0
   [junit4]   2> 522738 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[110 (1622176400228220928)]} 0 0
   [junit4]   2> 522739 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[111 (1622176400229269504)]} 0 0
   [junit4]   2> 522740 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[111 (1622176400230318080)]} 0 0
   [junit4]   2> 522741 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[112 (1622176400231366656)]} 0 0
   [junit4]   2> 522742 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[112 (1622176400231366656)]} 0 0
   [junit4]   2> 522743 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[113 (1622176400233463808)]} 0 0
   [junit4]   2> 522744 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[113 (1622176400233463808)]} 0 0
   [junit4]   2> 522745 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[114 (1622176400234512384)]} 0 0
   [junit4]   2> 522746 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[114 (1622176400235560960)]} 0 0
   [junit4]   2> 522747 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[115 (1622176400237658112)]} 0 0
   [junit4]   2> 522748 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[115 (1622176400238706688)]} 0 0
   [junit4]   2> 522749 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[116 (1622176400239755264)]} 0 0
   [junit4]   2> 522750 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[116 (1622176400240803840)]} 0 0
   [junit4]   2> 522751 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[117 (1622176400241852416)]} 0 0
   [junit4]   2> 522752 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[117 (1622176400242900992)]} 0 0
   [junit4]   2> 522753 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[118 (1622176400243949568)]} 0 0
   [junit4]   2> 522754 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[118 (1622176400243949568)]} 0 0
   [junit4]   2> 522755 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[119 (1622176400246046720)]} 0 0
   [junit4]   2> 522756 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[119 (1622176400246046720)]} 0 0
   [junit4]   2> 522757 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[120 (1622176400247095296)]} 0 0
   [junit4]   2> 522758 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[120 (1622176400248143872)]} 0 0
   [junit4]   2> 522759 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[121 (1622176400250241024)]} 0 0
   [junit4]   2> 522760 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[121 (1622176400250241024)]} 0 0
   [junit4]   2> 522761 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[122 (1622176400251289600)]} 0 0
   [junit4]   2> 522762 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[122 (1622176400252338176)]} 0 0
   [junit4]   2> 522763 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[123 (1622176400254435328)]} 0 0
   [junit4]   2> 522764 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[123 (1622176400254435328)]} 0 0
   [junit4]   2> 522765 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[124 (1622176400255483904)]} 0 0
   [junit4]   2> 522766 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[124 (1622176400257581056)]} 0 0
   [junit4]   2> 522767 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[125 (1622176400258629632)]} 0 0
   [junit4]   2> 522768 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[125 (1622176400258629632)]} 0 0
   [junit4]   2> 522769 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[126 (1622176400259678208)]} 0 0
   [junit4]   2> 522770 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[126 (1622176400260726784)]} 0 0
   [junit4]   2> 522771 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[127 (1622176400261775360)]} 0 0
   [junit4]   2> 522772 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[127 (1622176400262823936)]} 0 0
   [junit4]   2> 522773 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[128 (1622176400263872512)]} 0 0
   [junit4]   2> 522774 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[128 (1622176400264921088)]} 0 0
   [junit4]   2> 522775 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[129 (1622176400265969664)]} 0 0
   [junit4]   2> 522776 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[129 (1622176400267018240)]} 0 0
   [junit4]   2> 522777 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[130 (1622176400268066816)]} 0 0
   [junit4]   2> 522778 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[130 (1622176400269115392)]} 0 0
   [junit4]   2> 522778 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[131 (1622176400270163968)]} 0 0
   [junit4]   2> 522779 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[131 (1622176400271212544)]} 0 0
   [junit4]   2> 522780 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[132 (1622176400272261120)]} 0 0
   [junit4]   2> 522781 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[132 (1622176400273309696)]} 0 0
   [junit4]   2> 522782 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[133 (1622176400274358272)]} 0 0
   [junit4]   2> 522783 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[133 (1622176400275406848)]} 0 0
   [junit4]   2> 522784 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[134 (1622176400276455424)]} 0 0
   [junit4]   2> 522785 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[134 (1622176400277504000)]} 0 0
   [junit4]   2> 522786 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[135 (1622176400278552576)]} 0 0
   [junit4]   2> 522787 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[135 (1622176400279601152)]} 0 0
   [junit4]   2> 522788 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[136 (1622176400280649728)]} 0 0
   [junit4]   2> 522789 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[136 (1622176400280649728)]} 0 1
   [junit4]   2> 522794 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[137 (1622176400286941184)]} 0 0
   [junit4]   2> 522795 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[137 (1622176400287989760)]} 0 0
   [junit4]   2> 522796 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[138 (1622176400289038336)]} 0 0
   [junit4]   2> 522797 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[138 (1622176400290086912)]} 0 0
   [junit4]   2> 522798 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[139 (1622176400291135488)]} 0 0
   [junit4]   2> 522799 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[139 (1622176400292184064)]} 0 0
   [junit4]   2> 522801 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[140 (1622176400293232640)]} 0 1
   [junit4]   2> 522802 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[140 (1622176400294281216)]} 0 0
   [junit4]   2> 522803 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[141 (1622176400295329792)]} 0 0
   [junit4]   2> 522804 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[141 (1622176400296378368)]} 0 0
   [junit4]   2> 522805 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[142 (1622176400297426944)]} 0 0
   [junit4]   2> 522806 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[142 (1622176400298475520)]} 0 0
   [junit4]   2> 522807 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[143 (1622176400299524096)]} 0 0
   [junit4]   2> 522808 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[143 (1622176400300572672)]} 0 0
   [junit4]   2> 522808 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[144 (1622176400301621248)]} 0 0
   [junit4]   2> 522809 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[144 (1622176400302669824)]} 0 0
   [junit4]   2> 522810 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[145 (1622176400303718400)]} 0 0
   [junit4]   2> 522811 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[145 (1622176400304766976)]} 0 0
   [junit4]   2> 522812 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[146 (1622176400305815552)]} 0 0
   [junit4]   2> 522813 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[146 (1622176400306864128)]} 0 0
   [junit4]   2> 522814 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[147 (1622176400307912704)]} 0 0
   [junit4]   2> 522815 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[147 (1622176400307912704)]} 0 0
   [junit4]   2> 522816 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[148 (1622176400308961280)]} 0 0
   [junit4]   2> 522817 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[148 (1622176400310009856)]} 0 0
   [junit4]   2> 522818 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[149 (1622176400311058432)]} 0 0
   [junit4]   2> 522818 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[149 (1622176400312107008)]} 0 0
   [junit4]   2> 522819 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1622176400313155584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 522819 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2e67ffd2 commitCommandVersion:1622176400313155584
   [junit4]   2> 522836 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@60a6cf32[collection1] main]
   [junit4]   2> 522839 INFO  (searcherExecutor-1548-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@60a6cf32[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c9:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1547027969498}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):c59:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1547027969673}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 522839 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 522839 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 20
   [junit4]   2> 522840 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1622176400335175680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 522843 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@116b6b69 commitCommandVersion:1622176400335175680
   [junit4]   2> 522862 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1198cd4f[collection1] main]
   [junit4]   2> 522865 INFO  (searcherExecutor-1562-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1198cd4f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c9:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1547027969517}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):c59:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1547027969695}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 522865 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 522866 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 25
   [junit4]   2> 522868 ERROR (qtp2121296646-8671) [    x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
   [junit4]   2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
   [junit4]   2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:270)
   [junit4]   2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:185)
   [junit4]   2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272)
   [junit4]   2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
   [junit4]   2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2556)
   [junit4]   2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
   [junit4]   2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
   [junit4]   2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)
   [junit4]   2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
   [junit4]   2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
   [junit4]   2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
   [junit4]   2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
   [junit4]   2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
   [junit4]   2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
   [junit4]   2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:753)
   [junit4]   2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
   [junit4]   2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
   [junit4]   2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
   [junit4]   2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
   [junit4]   2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
   [junit4]   2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
   [junit4]   2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
   [junit4]   2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
   [junit4]   2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
   [junit4]   2> at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
   [junit4]   2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
   [junit4]   2> at java.lang.Integer.parseInt(Integer.java:580)
   [junit4]   2> at java.lang.Integer.valueOf(Integer.java:766)
   [junit4]   2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
   [junit4]   2> ... 34 more
   [junit4]   2>
   [junit4]   2> 522868 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 522869 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
   [junit4]   2> 522869 INFO  (qtp2121296646-8672) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 522870 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
   [junit4]   2> 522871 INFO  (qtp2121296646-8673) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 522872 ERROR (qtp2121296646-8674) [    x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
   [junit4]   2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
   [junit4]   2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:273)
   [junit4]   2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:185)
   [junit4]   2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272)
   [junit4]   2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
   [junit4]   2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2556)
   [junit4]   2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
   [junit4]   2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
   [junit4]   2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)
   [junit4]   2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
   [junit4]   2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
   [junit4]   2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
   [junit4]   2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
   [junit4]   2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
   [junit4]   2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
   [junit4]   2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
   [junit4]   2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
   [junit4]   2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:753)
   [junit4]   2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
   [junit4]   2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
   [junit4]   2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
   [junit4]   2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
   [junit4]   2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
   [junit4]   2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
   [junit4]   2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
   [junit4]   2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
   [junit4]   2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
   [junit4]   2> at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
   [junit4]   2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
   [junit4]   2> at java.lang.Integer.parseInt(Integer.java:580)
   [junit4]   2> at java.lang.Integer.valueOf(Integer.java:766)
   [junit4]   2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
   [junit4]   2> ... 34 more
   [junit4]   2>
   [junit4]   2> 522873 INFO  (qtp2121296646-8674) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} status=400 QTime=2
   [junit4]   2> 522875 INFO  (qtp2121296646-8670) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
   [junit4]   2> 522941 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969719&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 522946 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969719&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 522948 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=69
   [junit4]   2> 522952 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 522960 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969796&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 522963 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969796&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 522964 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
   [junit4]   2> 522968 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969806&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 522969 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969806&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 522970 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
   [junit4]   2> 522973 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969812&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 522974 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969812&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 522976 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
   [junit4]   2> 522979 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969807&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 522991 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969801&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 522992 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969807&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 522993 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=26
   [junit4]   2> 522995 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969801&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 522996 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=34
   [junit4]   2> 522998 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&rows=10&version=2&q=*:*&NOW=1547027969837&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 523000 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969837&ids=122,14,146,126,16,1001,129,108,119,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 523001 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
   [junit4]   2> 523003 INFO  (qtp2121296646-8671) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
   [junit4]   2> 523053 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1547027969845&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 523056 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969845&ids=144,1,113,124,147,148,107,141,10,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 523058 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=53
   [junit4]   2> 523059 INFO  (TEST-TestDistributedSearch.test-seed#[362C6D8DDAF5E9A]) [    ] o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 523067 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1547027969905&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 523068 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969905&ids=144,1,113,124,147,148,107,141,10,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 523070 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
   [junit4]   2> 523075 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1547027969914&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 523077 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969914&ids=144,1,113,124,147,148,107,141,10,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 523079 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
   [junit4]   2> 523081 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1547027969919&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 523081 INFO  (qtp151567408-8691) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1547027969920&isShard=true&wt=javabin} hits=68 status=0 QTime=0
   [junit4]   2> 523083 INFO  (qtp151567408-8694) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969919&ids=144,1,113,124,147,148,107,141,10,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 523084 INFO  (qtp151567408-8695) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1547027969920&ids=144,1,113,124,147,148,107,141,10,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 523085 INFO  (qtp151567408-8693) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
   [junit4]   2> 523085 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35414/collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
   [junit4]   2> 523088 INFO  (qtp151567408-8692) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+as

[...truncated too long message...]

ning 'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=362C6D8DDAF5E9A -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=el -Dtests.timezone=Europe/Budapest -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   91.6s J1 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.NullPointerException
   [junit4]    > at __randomizedtesting.SeedInfo.seed([362C6D8DDAF5E9A:8B36F90273533362]:0)
   [junit4]    > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:596)
   [junit4]    > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1096)
   [junit4]    > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1042)
   [junit4]    > at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_362C6D8DDAF5E9A-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@22d2b7f6), locale=el, timezone=Europe/Budapest
   [junit4]   2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=176755136,total=507510784
   [junit4]   2> NOTE: All tests run in this JVM: [DeleteShardTest, SyncSliceTest, TestEmbeddedSolrServerConstructors, TestManagedSynonymGraphFilterFactory, TestSlowCompositeReaderWrapper, TestOverriddenPrefixQueryForCustomFieldType, TermVectorComponentTest, BlockJoinFacetRandomTest, TestRuleBasedAuthorizationPlugin, TestSimLargeCluster, SolrGraphiteReporterTest, ConjunctionSolrSpellCheckerTest, SolrMetricReporterTest, CustomHighlightComponentTest, TestMultiValuedNumericRangeQuery, CurrencyFieldTypeTest, TestSolrCoreSnapshots, TestSolrDeletionPolicy1, TestFreeTextSuggestions, TestLockTree, TestInitQParser, ResponseBuilderTest, TestStressCloudBlindAtomicUpdates, TestHdfsUpdateLog, TestSmileRequest, TestSolrXml, TestCryptoKeys, LeaderTragicEventTest, TestReplicaProperties, TestHighlightDedupGrouping, ConfigureRecoveryStrategyTest, TestRecoveryHdfs, TestDistributedSearch]
   [junit4] Completed [139/837 (1!)] on J1 in 91.76s, 1 test, 1 error <<< FAILURES!

[...truncated 48142 lines...]
-ecj-javadoc-lint-src:
    [mkdir] Created dir: /tmp/ecj73257699
 [ecj-lint] Compiling 1241 source files to /tmp/ecj73257699
 [ecj-lint] Processing annotations
 [ecj-lint] Annotations processed
 [ecj-lint] Processing annotations
 [ecj-lint] No elements to process
 [ecj-lint] invalid Class-Path header in manifest of jar file: /x1/jenkins/.ivy2/cache/org.restlet.jee/org.restlet/jars/org.restlet-2.3.0.jar
 [ecj-lint] invalid Class-Path header in manifest of jar file: /x1/jenkins/.ivy2/cache/org.restlet.jee/org.restlet.ext.servlet/jars/org.restlet.ext.servlet-2.3.0.jar
 [ecj-lint] ----------
 [ecj-lint] 1. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/client/solrj/embedded/EmbeddedSolrServer.java (at line 219)
 [ecj-lint] return (NamedList<Object>) new JavaBinCodec(resolver).unmarshal(in);
 [ecj-lint]                           ^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 2. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/cloud/api/collections/RestoreCmd.java (at line 257)
 [ecj-lint] throw new SolrException(ErrorCode.BAD_REQUEST, "Unexpected number of replicas, replicationFactor, " +
 [ecj-lint]               Replica.Type.NRT + " or " + Replica.Type.TLOG + " must be greater than 0");
 [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'repository' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 3. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/handler/loader/JavabinLoader.java (at line 137)
 [ecj-lint] new JavaBinCodec() {
 [ecj-lint]       SolrParams params;
 [ecj-lint]       AddUpdateCommand addCmd = null;
 [ecj-lint]
 [ecj-lint]       @Override
 [ecj-lint]       public List<Object> readIterator(DataInputInputStream fis) throws IOException {
 [ecj-lint]         while (true) {
 [ecj-lint]           Object o = readVal(fis);
 [ecj-lint]           if (o == END_OBJ) break;
 [ecj-lint]           if (o instanceof NamedList) {
 [ecj-lint]             params = ((NamedList) o).toSolrParams();
 [ecj-lint]           } else {
 [ecj-lint]             try {
 [ecj-lint]               if (o instanceof byte[]) {
 [ecj-lint]                 if (params != null) req.setParams(params);
 [ecj-lint]                 byte[] buf = (byte[]) o;
 [ecj-lint]                 contentStreamLoader.load(req, rsp, new ContentStreamBase.ByteArrayStream(buf, null), processor);
 [ecj-lint]               } else {
 [ecj-lint]                 throw new RuntimeException("unsupported type ");
 [ecj-lint]               }
 [ecj-lint]             } catch (Exception e) {
 [ecj-lint]               throw new RuntimeException(e);
 [ecj-lint]             } finally {
 [ecj-lint]               params = null;
 [ecj-lint]               req.setParams(old);
 [ecj-lint]             }
 [ecj-lint]           }
 [ecj-lint]         }
 [ecj-lint]         return Collections.emptyList();
 [ecj-lint]       }
 [ecj-lint]
 [ecj-lint]     }.unmarshal(in);
 [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 4. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/update/DocumentBuilder.java (at line 32)
 [ecj-lint] import org.apache.solr.common.util.ByteArrayUtf8CharSequence;
 [ecj-lint]       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] The import org.apache.solr.common.util.ByteArrayUtf8CharSequence is never used
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 5. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/update/UpdateLog.java (at line 1867)
 [ecj-lint] if (exceptionOnExecuteUpdate.get() != null) throw exceptionOnExecuteUpdate.get();
 [ecj-lint]                                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'proc' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 6. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 50)
 [ecj-lint] in = new FileInputStream(src).getChannel();
 [ecj-lint]     ^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] 7. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 51)
 [ecj-lint] out = new FileOutputStream(destination).getChannel();
 [ecj-lint]      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] 7 problems (1 error, 6 warnings)

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:651: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:101: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build.xml:680: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/common-build.xml:2093: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/common-build.xml:2132: Compile failed; see the compiler error output for details.

Total time: 315 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]
Reply | Threaded
Open this post in threaded view
|

[JENKINS] Lucene-Solr-NightlyTests-master - Build # 1747 - Still Failing

Apache Jenkins Server-2
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1747/

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest

Error Message:
ObjectTracker found 3 object(s) that were not released!!! [MMapDirectory, InternalHttpClient, SolrCore] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MMapDirectory  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)  at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)  at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)  at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)  at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:422)  at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$13(ReplicationHandler.java:1182)  at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)  at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)  at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)  at java.lang.Thread.run(Thread.java:748)  org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)  at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)  at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)  at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:225)  at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:267)  at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:418)  at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:237)  at org.apache.solr.cloud.RecoveryStrategy.doReplicateOnlyRecovery(RecoveryStrategy.java:382)  at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:328)  at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:307)  at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)  at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)  at java.lang.Thread.run(Thread.java:748)  org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)  at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1055)  at org.apache.solr.core.SolrCore.<init>(SolrCore.java:875)  at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1189)  at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1099)  at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:92)  at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)  at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:395)  at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)  at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)  at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)  at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)  at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)  at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)  at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)  at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)  at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)  at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)  at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)  at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)  at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)  at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)  at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)  at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)  at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)  at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)  at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)  at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)  at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)  at org.eclipse.jetty.server.Server.handle(Server.java:502)  at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)  at org.eclipse.jetty.server.HttpChannel.run(HttpChannel.java:305)  at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)  at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)  at java.lang.Thread.run(Thread.java:748)   expected null, but was:<ObjectTracker found 3 object(s) that were not released!!! [MMapDirectory, InternalHttpClient, SolrCore] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MMapDirectory  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)  at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)  at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)  at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)  at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:422)  at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$13(ReplicationHandler.java:1182)  at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)  at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)  at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)  at java.lang.Thread.run(Thread.java:748)  org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)  at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)  at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)  at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:225)  at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:267)  at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:418)  at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:237)  at org.apache.solr.cloud.RecoveryStrategy.doReplicateOnlyRecovery(RecoveryStrategy.java:382)  at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:328)  at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:307)  at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)  at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)  at java.lang.Thread.run(Thread.java:748)  org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)  at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1055)  at org.apache.solr.core.SolrCore.<init>(SolrCore.java:875)  at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1189)  at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1099)  at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:92)  at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)  at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:395)  at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)  at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)  at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)  at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)  at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)  at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)  at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)  at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)  at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)  at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)  at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)  at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)  at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)  at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)  at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)  at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)  at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)  at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)  at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)  at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)  at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)  at org.eclipse.jetty.server.Server.handle(Server.java:502)  at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)  at org.eclipse.jetty.server.HttpChannel.run(HttpChannel.java:305)  at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)  at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)  at java.lang.Thread.run(Thread.java:748)  >

Stack Trace:
java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [MMapDirectory, InternalHttpClient, SolrCore]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MMapDirectory
        at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
        at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
        at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)
        at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:422)
        at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$13(ReplicationHandler.java:1182)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)

org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient
        at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)
        at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)
        at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:225)
        at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:267)
        at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:418)
        at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:237)
        at org.apache.solr.cloud.RecoveryStrategy.doReplicateOnlyRecovery(RecoveryStrategy.java:382)
        at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:328)
        at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:307)
        at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)

org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore
        at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1055)
        at org.apache.solr.core.SolrCore.<init>(SolrCore.java:875)
        at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1189)
        at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1099)
        at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:92)
        at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)
        at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:395)
        at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)
        at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
        at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)
        at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
        at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)
        at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)
        at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
        at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
        at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
        at org.eclipse.jetty.server.Server.handle(Server.java:502)
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
        at org.eclipse.jetty.server.HttpChannel.run(HttpChannel.java:305)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
        at java.lang.Thread.run(Thread.java:748)

 expected null, but was:<ObjectTracker found 3 object(s) that were not released!!! [MMapDirectory, InternalHttpClient, SolrCore]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MMapDirectory
        at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
        at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
        at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)
        at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:422)
        at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$13(ReplicationHandler.java:1182)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)

org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient
        at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)
        at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)
        at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:225)
        at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:267)
        at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:418)
        at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:237)
        at org.apache.solr.cloud.RecoveryStrategy.doReplicateOnlyRecovery(RecoveryStrategy.java:382)
        at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:328)
        at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:307)
        at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)

org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore
        at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1055)
        at org.apache.solr.core.SolrCore.<init>(SolrCore.java:875)
        at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1189)
        at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1099)
        at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:92)
        at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)
        at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:395)
        at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)
        at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
        at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)
        at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
        at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)
        at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)
        at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
        at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
        at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
        at org.eclipse.jetty.server.Server.handle(Server.java:502)
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
        at org.eclipse.jetty.server.HttpChannel.run(HttpChannel.java:305)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
        at java.lang.Thread.run(Thread.java:748)

>
        at __randomizedtesting.SeedInfo.seed([172B3465A9ED34BF]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotNull(Assert.java:755)
        at org.junit.Assert.assertNull(Assert.java:737)
        at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:319)
        at sun.reflect.GeneratedMethodAccessor89.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:1750)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:901)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.LeaderTragicEventTest.test

Error Message:
Timeout waiting for new replica become leader Timeout waiting to see state for collection=collection1 :DocCollection(collection1//collections/collection1/state.json/6)={   "pullReplicas":"0",   "replicationFactor":"2",   "shards":{"shard1":{       "range":"80000000-7fffffff",       "state":"active",       "replicas":{         "core_node3":{           "core":"collection1_shard1_replica_n1",           "base_url":"https://127.0.0.1:33063/solr",           "node_name":"127.0.0.1:33063_solr",           "state":"active",           "type":"NRT",           "force_set_state":"false"},         "core_node4":{           "core":"collection1_shard1_replica_n2",           "base_url":"https://127.0.0.1:42758/solr",           "node_name":"127.0.0.1:42758_solr",           "state":"active",           "type":"NRT",           "force_set_state":"false",           "leader":"true"}}}},   "router":{"name":"compositeId"},   "maxShardsPerNode":"1",   "autoAddReplicas":"false",   "nrtReplicas":"2",   "tlogReplicas":"0"} Live Nodes: [127.0.0.1:33063_solr, 127.0.0.1:42758_solr] Last available state: DocCollection(collection1//collections/collection1/state.json/6)={   "pullReplicas":"0",   "replicationFactor":"2",   "shards":{"shard1":{       "range":"80000000-7fffffff",       "state":"active",       "replicas":{         "core_node3":{           "core":"collection1_shard1_replica_n1",           "base_url":"https://127.0.0.1:33063/solr",           "node_name":"127.0.0.1:33063_solr",           "state":"active",           "type":"NRT",           "force_set_state":"false"},         "core_node4":{           "core":"collection1_shard1_replica_n2",           "base_url":"https://127.0.0.1:42758/solr",           "node_name":"127.0.0.1:42758_solr",           "state":"active",           "type":"NRT",           "force_set_state":"false",           "leader":"true"}}}},   "router":{"name":"compositeId"},   "maxShardsPerNode":"1",   "autoAddReplicas":"false",   "nrtReplicas":"2",   "tlogReplicas":"0"}

Stack Trace:
java.lang.AssertionError: Timeout waiting for new replica become leader
Timeout waiting to see state for collection=collection1 :DocCollection(collection1//collections/collection1/state.json/6)={
  "pullReplicas":"0",
  "replicationFactor":"2",
  "shards":{"shard1":{
      "range":"80000000-7fffffff",
      "state":"active",
      "replicas":{
        "core_node3":{
          "core":"collection1_shard1_replica_n1",
          "base_url":"https://127.0.0.1:33063/solr",
          "node_name":"127.0.0.1:33063_solr",
          "state":"active",
          "type":"NRT",
          "force_set_state":"false"},
        "core_node4":{
          "core":"collection1_shard1_replica_n2",
          "base_url":"https://127.0.0.1:42758/solr",
          "node_name":"127.0.0.1:42758_solr",
          "state":"active",
          "type":"NRT",
          "force_set_state":"false",
          "leader":"true"}}}},
  "router":{"name":"compositeId"},
  "maxShardsPerNode":"1",
  "autoAddReplicas":"false",
  "nrtReplicas":"2",
  "tlogReplicas":"0"}
Live Nodes: [127.0.0.1:33063_solr, 127.0.0.1:42758_solr]
Last available state: DocCollection(collection1//collections/collection1/state.json/6)={
  "pullReplicas":"0",
  "replicationFactor":"2",
  "shards":{"shard1":{
      "range":"80000000-7fffffff",
      "state":"active",
      "replicas":{
        "core_node3":{
          "core":"collection1_shard1_replica_n1",
          "base_url":"https://127.0.0.1:33063/solr",
          "node_name":"127.0.0.1:33063_solr",
          "state":"active",
          "type":"NRT",
          "force_set_state":"false"},
        "core_node4":{
          "core":"collection1_shard1_replica_n2",
          "base_url":"https://127.0.0.1:42758/solr",
          "node_name":"127.0.0.1:42758_solr",
          "state":"active",
          "type":"NRT",
          "force_set_state":"false",
          "leader":"true"}}}},
  "router":{"name":"compositeId"},
  "maxShardsPerNode":"1",
  "autoAddReplicas":"false",
  "nrtReplicas":"2",
  "tlogReplicas":"0"}
        at __randomizedtesting.SeedInfo.seed([172B3465A9ED34BF:9F7F0BBF07115947]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:289)
        at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:267)
        at org.apache.solr.cloud.LeaderTragicEventTest.test(LeaderTragicEventTest.java:84)
        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:1750)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.hdfs.StressHdfsTest.test

Error Message:
There are still nodes recoverying - waited for 330 seconds

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330 seconds
        at __randomizedtesting.SeedInfo.seed([172B3465A9ED34BF:9F7F0BBF07115947]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:195)
        at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:143)
        at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:138)
        at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:1032)
        at org.apache.solr.cloud.hdfs.StressHdfsTest.createAndDeleteCollection(StressHdfsTest.java:164)
        at org.apache.solr.cloud.hdfs.StressHdfsTest.test(StressHdfsTest.java:108)
        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:1750)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1070)
        at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1042)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 15056 lines...]
   [junit4] Suite: org.apache.solr.cloud.hdfs.StressHdfsTest
   [junit4]   2> 4252393 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/init-core-data-001
   [junit4]   2> 4252394 WARN  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16
   [junit4]   2> 4252394 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 4252395 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
   [junit4]   2> 4252395 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 4252683 WARN  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 4252791 WARN  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4252900 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 4253175 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_lucene2.us.west_apache_org_43603_hdfs____.wzthh8/webapp
   [junit4]   2> 4256006 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log Started HttpServer2$[hidden email]:43603
   [junit4]   2> 4257929 WARN  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4257932 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 4258150 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_38033_datanode____pmt84y/webapp
   [junit4]   2> 4260174 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38033
   [junit4]   2> 4260870 WARN  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4260936 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 4261516 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_34274_datanode____.20rkb/webapp
   [junit4]   2> 4262154 ERROR (DataNode: [[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/tempDir-001/hdfsBaseDir/data/data2/]]  heartbeating to lucene2-us-west.apache.org/127.0.0.1:39781) [    ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
   [junit4]   2> 4262612 INFO  (Block report processor) [    ] BlockStateChange BLOCK* processReport 0x68ab775bcd98b9: from storage DS-4ca05e63-6645-4da5-a2b3-7d61225c9710 node DatanodeRegistration(127.0.0.1:38666, datanodeUuid=7e826d63-bb5f-435b-8839-7f30b5f74261, infoPort=42480, infoSecurePort=0, ipcPort=35065, storageInfo=lv=-56;cid=testClusterID;nsid=2136519602;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
   [junit4]   2> 4262613 INFO  (Block report processor) [    ] BlockStateChange BLOCK* processReport 0x68ab775bcd98b9: from storage DS-89118912-9390-4558-bab3-d5040a91c5cc node DatanodeRegistration(127.0.0.1:38666, datanodeUuid=7e826d63-bb5f-435b-8839-7f30b5f74261, infoPort=42480, infoSecurePort=0, ipcPort=35065, storageInfo=lv=-56;cid=testClusterID;nsid=2136519602;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
   [junit4]   2> 4263950 INFO  (SUITE-StressHdfsTest-seed#[172B3465A9ED34BF]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34274
   [junit4]   2> 4265596 ERROR (DataNode: [[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/tempDir-001/hdfsBaseDir/data/data4/]]  heartbeating to lucene2-us-west.apache.org/127.0.0.1:39781) [    ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
   [junit4]   2> 4265788 INFO  (Block report processor) [    ] BlockStateChange BLOCK* processReport 0x68ab781c9e9789: from storage DS-b560efb6-19a6-4b50-9b54-4fa3789ef248 node DatanodeRegistration(127.0.0.1:43953, datanodeUuid=855d6297-4db2-40ae-b94e-5e3f51369f33, infoPort=40017, infoSecurePort=0, ipcPort=34352, storageInfo=lv=-56;cid=testClusterID;nsid=2136519602;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
   [junit4]   2> 4265796 INFO  (Block report processor) [    ] BlockStateChange BLOCK* processReport 0x68ab781c9e9789: from storage DS-7de99410-5831-4ab8-acad-c7b6a82223d5 node DatanodeRegistration(127.0.0.1:43953, datanodeUuid=855d6297-4db2-40ae-b94e-5e3f51369f33, infoPort=40017, infoSecurePort=0, ipcPort=34352, storageInfo=lv=-56;cid=testClusterID;nsid=2136519602;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
   [junit4]   2> 4266674 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4266687 INFO  (ZkTestServer Run Thread) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4266688 INFO  (ZkTestServer Run Thread) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 4266797 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer start zk server on port:34863
   [junit4]   2> 4266797 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34863
   [junit4]   2> 4266797 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34863
   [junit4]   2> 4266910 INFO  (zkConnectionManagerCallback-12266-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4266965 INFO  (zkConnectionManagerCallback-12268-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4266966 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 4266966 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 4266982 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 4266999 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 4267000 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 4267001 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 4267002 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 4267003 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 4267016 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 4267036 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 4267037 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 4267037 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
   [junit4]   2> 4268076 WARN  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 4268076 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 4268076 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 4268076 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c4b7917{/,null,AVAILABLE}
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@115d7359{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:33152}
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.s.Server Started @4268275ms
   [junit4]   2> 4268094 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://lucene2-us-west.apache.org:39781/hdfs__lucene2-us-west.apache.org_39781__home_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001_tempDir-002_control_data, hostContext=/, hostPort=33152, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/control-001/cores}
   [junit4]   2> 4268095 ERROR (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 4268095 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 4268095 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 9.0.0
   [junit4]   2> 4268095 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 4268095 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 4268095 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-01-10T12:48:51.323Z
   [junit4]   2> 4268118 INFO  (zkConnectionManagerCallback-12270-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4268119 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 4268119 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/control-001/solr.xml
   [junit4]   2> 4268155 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 4268155 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 4268157 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17dbb5bf, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 4269162 WARN  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1ff4c14f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4269304 WARN  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@62405039[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4269306 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34863/solr
   [junit4]   2> 4269382 INFO  (zkConnectionManagerCallback-12276-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4269543 INFO  (zkConnectionManagerCallback-12278-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4270276 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33152_
   [junit4]   2> 4270276 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.c.Overseer Overseer (id=73988411139620868-127.0.0.1:33152_-n_0000000000) starting
   [junit4]   2> 4270346 INFO  (zkConnectionManagerCallback-12285-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4270401 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34863/solr ready
   [junit4]   2> 4270435 INFO  (OverseerStateUpdate-73988411139620868-127.0.0.1:33152_-n_0000000000) [n:127.0.0.1:33152_    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33152_
   [junit4]   2> 4270435 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33152_
   [junit4]   2> 4270510 INFO  (OverseerStateUpdate-73988411139620868-127.0.0.1:33152_-n_0000000000) [n:127.0.0.1:33152_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4270597 INFO  (zkCallback-12284-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4270805 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 4270967 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17dbb5bf
   [junit4]   2> 4271105 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17dbb5bf
   [junit4]   2> 4271105 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17dbb5bf
   [junit4]   2> 4271106 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [n:127.0.0.1:33152_    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/control-001/cores
   [junit4]   2> 4271376 INFO  (zkConnectionManagerCallback-12291-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4271427 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4271501 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34863/solr ready
   [junit4]   2> 4271503 INFO  (qtp1163800265-31405) [n:127.0.0.1:33152_    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:33152_&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 4271628 INFO  (OverseerThreadFactory-5976-thread-1-processing-n:127.0.0.1:33152_) [n:127.0.0.1:33152_    ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
   [junit4]   2> 4271993 INFO  (qtp1163800265-31407) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
   [junit4]   2> 4271994 INFO  (qtp1163800265-31408) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
   [junit4]   2> 4272012 INFO  (qtp1163800265-31404) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
   [junit4]   2> 4272013 INFO  (qtp1163800265-31404) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
   [junit4]   2> 4272028 INFO  (qtp1163800265-31404) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
   [junit4]   2> 4272177 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_    x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 4272178 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_    x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 4273378 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 4273528 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
   [junit4]   2> 4274772 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4274882 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
   [junit4]   2> 4274899 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17dbb5bf
   [junit4]   2> 4274899 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://lucene2-us-west.apache.org:39781/solr_hdfs_home
   [junit4]   2> 4274899 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 4274899 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[hdfs://lucene2-us-west.apache.org:39781/solr_hdfs_home/control_collection/core_node2/data/]
   [junit4]   2> 4274900 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://lucene2-us-west.apache.org:39781/solr_hdfs_home/control_collection/core_node2/data/snapshot_metadata
   [junit4]   2> 4274972 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true]
   [junit4]   2> 4274972 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes
   [junit4]   2> 4274972 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
   [junit4]   2> 4275154 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.s.b.BlockDirectory Block cache on write is disabled
   [junit4]   2> 4275171 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://lucene2-us-west.apache.org:39781/solr_hdfs_home/control_collection/core_node2/data
   [junit4]   2> 4275383 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://lucene2-us-west.apache.org:39781/solr_hdfs_home/control_collection/core_node2/data/index
   [junit4]   2> 4275436 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true]
   [junit4]   2> 4275436 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes
   [junit4]   2> 4275436 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
   [junit4]   2> 4275639 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.s.b.BlockDirectory Block cache on write is disabled
   [junit4]   2> 4275639 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=8, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.8740234375, noCFSRatio=0.0]
   [junit4]   2> 4275984 INFO  (Block report processor) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38666 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7de99410-5831-4ab8-acad-c7b6a82223d5:NORMAL:127.0.0.1:43953|RBW], ReplicaUC[[DISK]DS-89118912-9390-4558-bab3-d5040a91c5cc:NORMAL:127.0.0.1:38666|RBW]]} size 0
   [junit4]   2> 4275999 INFO  (Block report processor) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43953 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7de99410-5831-4ab8-acad-c7b6a82223d5:NORMAL:127.0.0.1:43953|RBW], ReplicaUC[[DISK]DS-89118912-9390-4558-bab3-d5040a91c5cc:NORMAL:127.0.0.1:38666|RBW]]} size 0
   [junit4]   2> 4276108 WARN  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 4277042 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
   [junit4]   2> 4277042 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 4277042 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
   [junit4]   2> 4277152 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4277152 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4277154 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=34.5654296875, floorSegmentMB=1.970703125, forceMergeDeletesPctAllowed=11.770856420885604, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.596143963733689, deletesPctAllowed=40.91588214413159
   [junit4]   2> 4277280 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a76f5d8[control_collection_shard1_replica_n1] main]
   [junit4]   2> 4277315 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 4277316 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 4277316 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4277317 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1622277662226513920
   [junit4]   2> 4277372 INFO  (searcherExecutor-5981-thread-1-processing-n:127.0.0.1:33152_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@6a76f5d8[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4277409 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
   [junit4]   2> 4277409 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
   [junit4]   2> 4277423 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 4277423 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 4277423 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33152/control_collection_shard1_replica_n1/
   [junit4]   2> 4277424 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 4277424 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:33152/control_collection_shard1_replica_n1/ has no replicas
   [junit4]   2> 4277424 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/73988411139620868-core_node2-n_0000000000
   [junit4]   2> 4277425 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33152/control_collection_shard1_replica_n1/ shard1
   [junit4]   2> 4277497 INFO  (zkCallback-12277-thread-2) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4277497 INFO  (zkCallback-12277-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4277544 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 4277545 ERROR (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.c.c.ZkStateReader Collection control_collection is not lazy or watched!
   [junit4]   2> 4277546 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_ c:control_collection s:shard1  x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=5369
   [junit4]   2> 4277626 INFO  (qtp1163800265-31405) [n:127.0.0.1:33152_    ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
   [junit4]   2> 4277774 INFO  (zkCallback-12277-thread-3) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4277775 INFO  (zkCallback-12277-thread-2) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4277775 INFO  (zkCallback-12277-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4277792 INFO  (qtp1163800265-31405) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:33152_&wt=javabin&version=2} status=0 QTime=6289
   [junit4]   2> 4277871 INFO  (OverseerCollectionConfigSetProcessor-73988411139620868-127.0.0.1:33152_-n_0000000000) [n:127.0.0.1:33152_    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 4277897 INFO  (zkConnectionManagerCallback-12296-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4277905 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4277906 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34863/solr ready
   [junit4]   2> 4277906 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 4277907 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 4277922 INFO  (OverseerThreadFactory-5976-thread-2-processing-n:127.0.0.1:33152_) [n:127.0.0.1:33152_    ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
   [junit4]   2> 4278334 WARN  (OverseerThreadFactory-5976-thread-2-processing-n:127.0.0.1:33152_) [n:127.0.0.1:33152_    ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
   [junit4]   2> 4278341 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_    ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
   [junit4]   2> 4278342 INFO  (qtp1163800265-31406) [n:127.0.0.1:33152_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=435
   [junit4]   2> 4278408 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=7
   [junit4]   2> 4279802 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/shard-1-001 of type NRT
   [junit4]   2> 4279817 WARN  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 4279817 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 4279818 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 4279818 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 4279928 INFO  (OverseerCollectionConfigSetProcessor-73988411139620868-127.0.0.1:33152_-n_0000000000) [n:127.0.0.1:33152_    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 4279968 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 4279968 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 4279968 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 4280020 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75dbbacf{/,null,AVAILABLE}
   [junit4]   2> 4280020 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@78a9f00d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40579}
   [junit4]   2> 4280020 INFO  (closeThreadPool-12297-thread-1) [    ] o.e.j.s.Server Started @4280201ms
   [junit4]   2> 4280020 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://lucene2-us-west.apache.org:39781/hdfs__lucene2-us-west.apache.org_39781__home_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=40579, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/shard-1-001/cores}
   [junit4]   2> 4280020 ERROR (closeThreadPool-12297-thread-1) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 4280021 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 4280021 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 9.0.0
   [junit4]   2> 4280021 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 4280021 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 4280021 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-01-10T12:49:03.249Z
   [junit4]   2> 4280077 INFO  (zkConnectionManagerCallback-12299-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4280094 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 4280094 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/shard-1-001/solr.xml
   [junit4]   2> 4280134 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 4280134 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 4280135 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17dbb5bf, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 4281094 WARN  (closeThreadPool-12297-thread-1) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6a5fb22[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4281151 WARN  (closeThreadPool-12297-thread-1) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7730e539[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4281152 INFO  (closeThreadPool-12297-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34863/solr
   [junit4]   2> 4281305 INFO  (zkConnectionManagerCallback-12305-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4281486 INFO  (zkConnectionManagerCallback-12307-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 4281500 INFO  (closeThreadPool-12297-thread-1) [n:127.0.0.1:40579_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4281531 INFO  (closeThreadPool-12297-thread-1) [n:127.0.0.1:40579_    ] o.a.s.c.ZkController Publish node=127.0.0.1:40579_ as DOWN
   [junit4]   2> 4281532 INFO  (closeThreadPool-12297-thread-1) [n:127.0.0.1:40579_    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 4281532 INFO  (closeThreadPool-12297-thread-1) [n:127.0.0.1:40579_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40579_
   [junit4]   2> 4281533 INFO  (zkCallback-12277-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 4281533 INFO  (zkCallback-12295-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 4281534 INFO  (zkCallback-12284-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 4281555 INFO  (zkCallback-12306-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 4281695 INFO  (TEST-StressHdfsTest.test-seed#[172B3465A9ED34BF]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/shard-2-001 of type NRT
   [junit4]   2> 4281733 WARN  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 4281734 INFO  (closeThreadPool-12297-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 4281734 INFO  (closeThreadPool-12297-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 4281734 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 4281794 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 4281794 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 4281794 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 4281816 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@48ef07b4{/,null,AVAILABLE}
   [junit4]   2> 4281822 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5768bd39{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44713}
   [junit4]   2> 4281822 INFO  (closeThreadPool-12297-thread-2) [    ] o.e.j.s.Server Started @4282003ms
   [junit4]   2> 4281822 INFO  (closeThreadPool-12297-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://lucene2-us-west.apache.org:39781/hdfs__lucene2-us-west.apache.org_39781__home_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001_tempDir-002_jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=44713, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.StressHdfsTest_172B3465A9ED34BF-001/shard-2-001/cores}
   [junit4]   2> 4281823 ERROR (closeThreadPool-12297-thread-2) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 4281831 INFO  (closeThreadPool-12297-th

[...truncated too long message...]

ctoryTest, ResponseLogComponentTest, SpatialRPTFieldTypeTest, TestStressInPlaceUpdates, TestReversedWildcardFilterFactory, BitVectorTest, TestSchemaNameResource, HdfsSyncSliceTest, PreAnalyzedFieldTest, TestCodecSupport, FieldAnalysisRequestHandlerTest, TestCloudPseudoReturnFields, TestCollectionAPIs, TestPayloadCheckQParserPlugin, TestNestedDocsSort, ManagedSchemaRoundRobinCloudTest, TestCollationField, DebugComponentTest, SpellCheckComponentTest, TestLFUCache, BasicDistributedZkTest, TolerantUpdateProcessorTest, TriLevelCompositeIdRoutingTest, TestSolr4Spatial, CoreAdminCreateDiscoverTest, CollectionStateFormat2Test, TestConfigSetsAPIExclusivity, SolrJmxReporterCloudTest, TestReplicationHandler, DistribJoinFromCollectionTest, TestOrdValues, MetricsHandlerTest, TestHashQParserPlugin, TestBlendedInfixSuggestions, TestRandomRequestDistribution, TestPerFieldSimilarity, TestBM25SimilarityFactory, TestQueryTypes, PhrasesIdentificationComponentTest, DocValuesNotIndexedTest, DeleteNodeTest, DOMUtilTest, TestBulkSchemaConcurrent, TestSimDistribStateManager, XsltUpdateRequestHandlerTest, MigrateRouteKeyTest, HLLSerializationTest, SimpleCollectionCreateDeleteTest, TestTrie, TestFastOutputStream, TestDocBasedVersionConstraints, ConfigSetsAPITest, TestCustomStream, LargeFieldTest, TestCloudSearcherWarming, TestClusterStateMutator, TestSubQueryTransformerDistrib, BinaryUpdateRequestHandlerTest, ResponseHeaderTest, TestDistributedGrouping, TestSearcherReuse, TestMaxScoreQueryParser, StatsReloadRaceTest, TestPushWriter, TestDFRSimilarityFactory, TermsComponentTest, TestValueSourceCache, ResourceLoaderTest, TestManagedStopFilterFactory, TestComplexPhraseQParserPlugin, TestAuthorizationFramework, DirectUpdateHandlerTest, SchemaApiFailureTest, SolrMetricsIntegrationTest, AssignBackwardCompatibilityTest, TestMultiWordSynonyms, TestJsonFacets, MoveReplicaHDFSTest, SliceStateTest, TestReload, TestRetrieveFieldsOptimizer, HdfsDirectoryTest, TestRTimerTree, TestDocTermOrdsUninvertLimit, TestNumericTerms64, TestConfigSetImmutable, TestSolrFieldCacheBean, TestQuerySenderNoQuery, TestRequestStatusCollectionAPI, CreateRoutedAliasTest, TestAuthenticationFramework, TestFiltering, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, TestJoin, TestRandomDVFaceting, TestRandomFaceting, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ChaosMonkeySafeLeaderWithPullReplicasTest, DeleteShardTest, DeleteStatusTest, HttpPartitionOnCommitTest, LeaderElectionContextKeyTest, LeaderFailureAfterFreshStartTest, LeaderTragicEventTest]
   [junit4] Completed [457/837 (3!)] on J2 in 54.15s, 2 tests, 1 failure <<< FAILURES!

[...truncated 47069 lines...]
-ecj-javadoc-lint-src:
    [mkdir] Created dir: /tmp/ecj819044783
 [ecj-lint] Compiling 1241 source files to /tmp/ecj819044783
 [ecj-lint] Processing annotations
 [ecj-lint] Annotations processed
 [ecj-lint] Processing annotations
 [ecj-lint] No elements to process
 [ecj-lint] invalid Class-Path header in manifest of jar file: /home/jenkins/.ivy2/cache/org.restlet.jee/org.restlet/jars/org.restlet-2.3.0.jar
 [ecj-lint] invalid Class-Path header in manifest of jar file: /home/jenkins/.ivy2/cache/org.restlet.jee/org.restlet.ext.servlet/jars/org.restlet.ext.servlet-2.3.0.jar
 [ecj-lint] ----------
 [ecj-lint] 1. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/client/solrj/embedded/EmbeddedSolrServer.java (at line 219)
 [ecj-lint] return (NamedList<Object>) new JavaBinCodec(resolver).unmarshal(in);
 [ecj-lint]                           ^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 2. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/cloud/api/collections/RestoreCmd.java (at line 257)
 [ecj-lint] throw new SolrException(ErrorCode.BAD_REQUEST, "Unexpected number of replicas, replicationFactor, " +
 [ecj-lint]               Replica.Type.NRT + " or " + Replica.Type.TLOG + " must be greater than 0");
 [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'repository' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 3. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/handler/loader/JavabinLoader.java (at line 137)
 [ecj-lint] new JavaBinCodec() {
 [ecj-lint]       SolrParams params;
 [ecj-lint]       AddUpdateCommand addCmd = null;
 [ecj-lint]
 [ecj-lint]       @Override
 [ecj-lint]       public List<Object> readIterator(DataInputInputStream fis) throws IOException {
 [ecj-lint]         while (true) {
 [ecj-lint]           Object o = readVal(fis);
 [ecj-lint]           if (o == END_OBJ) break;
 [ecj-lint]           if (o instanceof NamedList) {
 [ecj-lint]             params = ((NamedList) o).toSolrParams();
 [ecj-lint]           } else {
 [ecj-lint]             try {
 [ecj-lint]               if (o instanceof byte[]) {
 [ecj-lint]                 if (params != null) req.setParams(params);
 [ecj-lint]                 byte[] buf = (byte[]) o;
 [ecj-lint]                 contentStreamLoader.load(req, rsp, new ContentStreamBase.ByteArrayStream(buf, null), processor);
 [ecj-lint]               } else {
 [ecj-lint]                 throw new RuntimeException("unsupported type ");
 [ecj-lint]               }
 [ecj-lint]             } catch (Exception e) {
 [ecj-lint]               throw new RuntimeException(e);
 [ecj-lint]             } finally {
 [ecj-lint]               params = null;
 [ecj-lint]               req.setParams(old);
 [ecj-lint]             }
 [ecj-lint]           }
 [ecj-lint]         }
 [ecj-lint]         return Collections.emptyList();
 [ecj-lint]       }
 [ecj-lint]
 [ecj-lint]     }.unmarshal(in);
 [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 4. ERROR in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/update/DocumentBuilder.java (at line 32)
 [ecj-lint] import org.apache.solr.common.util.ByteArrayUtf8CharSequence;
 [ecj-lint]       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] The import org.apache.solr.common.util.ByteArrayUtf8CharSequence is never used
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 5. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/update/UpdateLog.java (at line 1867)
 [ecj-lint] if (exceptionOnExecuteUpdate.get() != null) throw exceptionOnExecuteUpdate.get();
 [ecj-lint]                                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'proc' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 6. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 50)
 [ecj-lint] in = new FileInputStream(src).getChannel();
 [ecj-lint]     ^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] 7. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 51)
 [ecj-lint] out = new FileOutputStream(destination).getChannel();
 [ecj-lint]      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] 7 problems (1 error, 6 warnings)

BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:651: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:101: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build.xml:680: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/common-build.xml:2093: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/common-build.xml:2132: Compile failed; see the compiler error output for details.

Total time: 601 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]