Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24434/
Java: 64bit/jdk-13-ea+shipilev-fastdebug -XX:+UseCompressedOops 
-XX:+UseParallelGC

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.response.TestSolrQueryResponse

Error Message:
The test or suite printed 137148 bytes to stdout and stderr, even though the 
limit was set to 8192 bytes. Increase the limit with @Limit, ignore it 
completely with @SuppressSysoutChecks or run with -Dtests.verbose=true

Stack Trace:
java.lang.AssertionError: The test or suite printed 137148 bytes to stdout and 
stderr, even though the limit was set to 8192 bytes. Increase the limit with 
@Limit, ignore it completely with @SuppressSysoutChecks or run with 
-Dtests.verbose=true
        at __randomizedtesting.SeedInfo.seed([82B64838C33BAD61]:0)
        at 
org.apache.lucene.util.TestRuleLimitSysouts.afterIfSuccessful(TestRuleLimitSysouts.java:282)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterIfSuccessful(TestRuleAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:37)
        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.base/java.lang.Thread.run(Thread.java:830)


FAILED:  
org.apache.solr.cloud.MetricsHistoryWithAuthIntegrationTest.testValuesAreCollected

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([82B64838C33BAD61:AA4B155F12DF3224]:0)
        at org.junit.Assert.fail(Assert.java:86)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at org.junit.Assert.assertNotNull(Assert.java:712)
        at org.junit.Assert.assertNotNull(Assert.java:722)
        at 
org.apache.solr.cloud.MetricsHistoryWithAuthIntegrationTest.testValuesAreCollected(MetricsHistoryWithAuthIntegrationTest.java:86)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        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 
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.base/java.lang.Thread.run(Thread.java:830)




Build Log:
[...truncated 12851 lines...]
   [junit4] Suite: org.apache.solr.cloud.MetricsHistoryWithAuthIntegrationTest
   [junit4]   2> 50650 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.MetricsHistoryWithAuthIntegrationTest_82B64838C33BAD61-001/init-core-data-001
   [junit4]   2> 50652 WARN  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=26 numCloses=26
   [junit4]   2> 50653 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 50691 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 50754 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.MetricsHistoryWithAuthIntegrationTest_82B64838C33BAD61-001/tempDir-001
   [junit4]   2> 50818 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 50838 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 50838 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 51351 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer start zk server on port:37351
   [junit4]   2> 51360 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37351
   [junit4]   2> 51370 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37351
   [junit4]   2> 52221 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 52472 INFO  (zkConnectionManagerCallback-219-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 52473 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 52782 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 52827 INFO  (zkConnectionManagerCallback-221-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 52827 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 52860 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 52882 INFO  (zkConnectionManagerCallback-223-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 52883 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53747 WARN  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 54936 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 54936 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 54988 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: 
afcf563148970e98786327af5e07c261fda175d3; jvm 
13-testing+0-builds.shipilev.net-openjdk-jdk13-b9-20190621-jdk-1326
   [junit4]   2> 55124 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 55124 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 55162 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 55391 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4e33f874{/solr,null,AVAILABLE}
   [junit4]   2> 55425 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@4e5d47c4{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:36259}
   [junit4]   2> 55426 INFO  (jetty-launcher-224-thread-1) [     ] 
o.e.j.s.Server Started @55695ms
   [junit4]   2> 55426 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36259}
   [junit4]   2> 55649 ERROR (jetty-launcher-224-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 55649 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 55649 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 55650 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 55650 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 55651 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-07-23T00:26:46.755059Z
   [junit4]   2> 55676 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 55683 INFO  (zkConnectionManagerCallback-226-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 55683 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 55693 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 56085 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 56109 WARN  (jetty-launcher-224-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2a726e18[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 56109 WARN  (jetty-launcher-224-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2a726e18[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 56146 WARN  (jetty-launcher-224-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@1240ceab[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 56146 WARN  (jetty-launcher-224-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@1240ceab[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 56149 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37351/solr
   [junit4]   2> 56339 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 56372 INFO  (zkConnectionManagerCallback-233-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 56372 INFO  (jetty-launcher-224-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 56532 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 56564 INFO  (zkConnectionManagerCallback-235-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 56564 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 57692 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:36259_solr
   [junit4]   2> 57722 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.Overseer Overseer 
(id=72211955894255621-127.0.0.1:36259_solr-n_0000000000) starting
   [junit4]   2> 58188 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 58192 INFO  (zkConnectionManagerCallback-242-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 58192 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 58205 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:37351/solr ready
   [junit4]   2> 58721 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36259_solr
   [junit4]   2> 58735 INFO  
(OverseerStateUpdate-72211955894255621-127.0.0.1:36259_solr-n_0000000000) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:36259_solr
   [junit4]   2> 59014 INFO  (zkCallback-241-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 59015 INFO  (zkCallback-234-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 59249 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.LibListener clusterprops.json changed , 
version -1
   [junit4]   2> 59567 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 59890 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 60152 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 61086 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36259.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b6205f
   [junit4]   2> 61087 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36259.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b6205f
   [junit4]   2> 61090 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36259.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b6205f
   [junit4]   2> 61173 INFO  (jetty-launcher-224-thread-1) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.MetricsHistoryWithAuthIntegrationTest_82B64838C33BAD61-001/tempDir-001/node1/.
   [junit4]   2> 62083 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 62105 INFO  (zkConnectionManagerCallback-248-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 62105 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 62107 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 62109 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr 
ready
   [junit4]   2> 62142 DEBUG (MetricsHistoryHandler-477-thread-1) [     ] 
o.a.s.h.a.MetricsHistoryHandler -- collectMetrics
   [junit4]   2> 65298 INFO  
(TEST-MetricsHistoryWithAuthIntegrationTest.testValuesAreCollected-seed#[82B64838C33BAD61])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testValuesAreCollected
   [junit4]   2> 65794 INFO  (qtp87068486-498) [n:127.0.0.1:36259_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key 
params={omitHeader=true&wt=json} status=0 QTime=10
   [junit4]   2> 65929 INFO  (qtp87068486-495) [n:127.0.0.1:36259_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics/history 
params={name=solr.jvm&action=get&wt=javabin&version=2} status=0 QTime=153
   [junit4]   2> 66320 INFO  (qtp87068486-497) [     ] 
o.a.s.s.PKIAuthenticationPlugin New Key obtained from  node: 
127.0.0.1:36259_solr / 
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAnKuFejMSBSB7d5MJueMry2XE5tejWPEN/OUEcns5/DQLxa9/h2idJVVU5DBqe4/5xTy7wL00xn4NLca0f/TKbK/5EmPkOeT8Op7fX7Z+xS9Lt0XhgyelIlyd7K61vq/eDosQ5neKMyQSrPGr11F0eglXQIOG8aruD/loH8p/yY2jSsGCaSpQlPkTB4Guh/JJHdE2UL8EnqeJ6xVaNEUmnzI8A1wvxfCxj97rtdQYsikhMFzQ57qL2FbguICVid2WblXGRFGm9TpyuZt1d2jnJ8WboWkzEaSPNnpurhkJXeg6kjeqYSdlSDPWledRXKyLqCG1ctErkr/TFwkzHsHOtQIDAQAB
   [junit4]   2> 66392 INFO  
(TEST-MetricsHistoryWithAuthIntegrationTest.testValuesAreCollected-seed#[82B64838C33BAD61])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testValuesAreCollected
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=MetricsHistoryWithAuthIntegrationTest 
-Dtests.method=testValuesAreCollected -Dtests.seed=82B64838C33BAD61 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hi 
-Dtests.timezone=Asia/Calcutta -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 1.32s J0 | 
MetricsHistoryWithAuthIntegrationTest.testValuesAreCollected <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([82B64838C33BAD61:AA4B155F12DF3224]:0)
   [junit4]    >        at 
org.apache.solr.cloud.MetricsHistoryWithAuthIntegrationTest.testValuesAreCollected(MetricsHistoryWithAuthIntegrationTest.java:86)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:567)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:830)
   [junit4]   2> 66567 INFO  (qtp87068486-497) [n:127.0.0.1:36259_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.jvm:os.processCpuLoad&key=solr.node:CONTAINER.fs.coreRoot.usableSpace&key=solr.jvm:os.systemLoadAverage&key=solr.jvm:memory.heap.used}
 status=0 QTime=64
   [junit4]   2> 66638 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=138323516
   [junit4]   2> 66638 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36259_solr
   [junit4]   2> 66712 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 66712 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.c.ZkController Publish node=127.0.0.1:36259_solr as DOWN
   [junit4]   2> 66873 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 66874 DEBUG (jetty-closer-249-thread-1) [     ] 
o.a.s.h.a.MetricsHistoryHandler Closing 1468224706
   [junit4]   2> 67324 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 67325 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7285045b: rootName = 
solr_36259, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@181679cb
   [junit4]   2> 67354 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 67354 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@ba8f16e: rootName = 
solr_36259, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@6441e3f4
   [junit4]   2> 67362 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 67362 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@31815ccb: rootName = 
solr_36259, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@792da1b5
   [junit4]   2> 67362 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 67424 INFO  (closeThreadPool-253-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72211955894255621-127.0.0.1:36259_solr-n_0000000000) closing
   [junit4]   2> 67424 INFO  
(OverseerStateUpdate-72211955894255621-127.0.0.1:36259_solr-n_0000000000) 
[n:127.0.0.1:36259_solr     ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:36259_solr
   [junit4]   2> 67424 INFO  
(OverseerAutoScalingTriggerThread-72211955894255621-127.0.0.1:36259_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 67463 INFO  (closeThreadPool-253-thread-2) [     ] 
o.a.s.c.Overseer Overseer 
(id=72211955894255621-127.0.0.1:36259_solr-n_0000000000) closing
   [junit4]   2> 67708 INFO  (jetty-closer-249-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72211955894255621-127.0.0.1:36259_solr-n_0000000000) closing
   [junit4]   2> 67785 INFO  (jetty-closer-249-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@4e5d47c4{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 67806 INFO  (jetty-closer-249-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4e33f874{/solr,null,UNAVAILABLE}
   [junit4]   2> 67806 INFO  (jetty-closer-249-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 67809 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 68033 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/aliases.json
   [junit4]   2>        3       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/live_nodes
   [junit4]   2>        3       /solr/overseer/queue
   [junit4]   2>        3       /solr/collections
   [junit4]   2> 
   [junit4]   2> 68036 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37351
   [junit4]   2> 68036 INFO  
(SUITE-MetricsHistoryWithAuthIntegrationTest-seed#[82B64838C33BAD61]-worker) [  
   ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37351
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.MetricsHistoryWithAuthIntegrationTest_82B64838C33BAD61-001
   [junit4]   2> Jul 23, 2019 12:26:59 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): {}, 
docValues:{}, maxPointsInLeafNode=550, maxMBSortInHeap=7.423826292912674, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@279b3410),
 locale=hi, timezone=Asia/Calcutta
   [junit4]   2> NOTE: Linux 4.18.0-24-generic amd64/Aleksey Shipilev 
13-testing (64-bit)/cpus=8,threads=1,free=370804040,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [ResourceLoaderTest, 
TestRTimerTree, TestManagedSchema, TestSystemIdResolver, TestDocSet, 
TestWriterPerf, FacetPivotSmallTest, MetricsHistoryWithAuthIntegrationTest]
   [junit4] Completed [29/871 (1!)] on J0 in 18.65s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 1097 lines...]
   [junit4] Suite: org.apache.solr.response.TestSolrQueryResponse
   [junit4]   2> 599983 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 
QTime=0
   [junit4]   2> 599984 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:1&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=1 status=0 
QTime=1
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=0
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599984 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=0
   [junit4]   2> 599984 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599984 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599984 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=1
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599985 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599985 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:5&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=4
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599986 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599986 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=8
   [junit4]   2> 599987 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=2
   [junit4]   2> 599987 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599987 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599987 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599987 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599988 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599988 INFO  (READER7) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599988 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599988 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=6
   [junit4]   2> 599988 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=5
   [junit4]   2> 599988 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599988 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599988 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 599989 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599989 INFO  (READER11) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 
QTime=11
   [junit4]   2> 599989 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599989 INFO  (READER11) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 
QTime=0
   [junit4]   2> 599989 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599989 INFO  (READER2) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 
QTime=9
   [junit4]   2> 599989 INFO  (READER11) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599989 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599989 INFO  (READER2) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599989 INFO  (READER11) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 
QTime=0
   [junit4]   2> 599989 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599989 INFO  (READER2) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599989 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=1
   [junit4]   2> 599989 INFO  (READER11) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 
QTime=0
   [junit4]   2> 599990 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
   [junit4]   2> 599990 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=1
   [junit4]   2> 599990 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=3
   [junit4]   2> 599990 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 
QTime=3
   [junit4]   2> 599990 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=9
   [junit4]   2> 599990 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 
QTime=0
   [junit4]   2> 599990 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=3
   [junit4]   2> 599990 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=8
   [junit4]   2> 599990 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 
QTime=0
   [junit4]   2> 599990 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599990 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599990 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=1 status=0 
QTime=1
   [junit4]   2> 599991 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599991 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599991 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599991 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 599991 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=1
   [junit4]   2> 599992 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599992 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=1
   [junit4]   2> 599992 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=2
   [junit4]   2> 599992 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599992 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599992 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599992 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599992 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=2
   [junit4]   2> 599993 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599993 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:5&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599993 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=2
   [junit4]   2> 599994 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=2
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599994 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599994 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=1
   [junit4]   2> 599995 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:18&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:11&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 599995 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599995 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599996 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599996 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599996 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599996 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599996 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599996 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=1
   [junit4]   2> 599997 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599997 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=1
   [junit4]   2> 599997 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=7
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599997 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599997 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 599997 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
   [junit4]   2> 599998 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=1
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599998 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 599998 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=2
   [junit4]   2> 599999 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 
QTime=1
   [junit4]   2> 599999 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=1
   [junit4]   2> 599999 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER9) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=18
   [junit4]   2> 599999 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=3
   [junit4]   2> 599999 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 599999 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 
QTime=0
   [junit4]   2> 599999 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=0
   [junit4]   2> 600000 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=1
   [junit4]   2> 600000 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=1 status=0 
QTime=2
   [junit4]   2> 600000 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 600000 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 
QTime=0
   [junit4]   2> 600000 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:15&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:17&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER0) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:3&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER4) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 600000 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 
QTime=0
   [junit4]   2> 600000 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 
QTime=0
   [junit4]   2> 600000 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER12) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER1) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=2
   [junit4]   2> 600001 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:10&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 
QTime=0
   [junit4]   2> 600001 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER8) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER6) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:16&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER5) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 600001 INFO  (READER3) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=2
   [junit4]   2> 600002 INFO  (READER10) [     ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 
QTime=0
   [junit4]   2> 600002 INFO  (READER2) [     ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=12
   [junit4]   2> 600002 INFO  (READER0) [  

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

ReorderVersions
   [junit4]   2> 600119 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 600119 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=722858075
   [junit4]   2> 600140 INFO  (coreCloseExecutor-1830-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7cb2a2f0
   [junit4]   2> 600140 INFO  (coreCloseExecutor-1830-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=7cb2a2f0
   [junit4]   2> 600140 INFO  (coreCloseExecutor-1830-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@53f142cb: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5c2b74a3
   [junit4]   2> 600180 INFO  (coreCloseExecutor-1830-thread-1) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 600180 INFO  (coreCloseExecutor-1830-thread-1) [    
x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5cf7d009 commitCommandVersion:0
   [junit4]   2> 600252 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 600252 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@64759d23: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@f8d0e0b
   [junit4]   2> 600265 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 600265 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5255109d: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5532c961
   [junit4]   2> 600291 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 600291 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@61312233: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@2116b650
   [junit4]   2> 600305 INFO  
(SUITE-TestStressReorder-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> 600489 INFO  
(SUITE-TestSha256AuthenticationProvider-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 600490 WARN  
(SUITE-TestSha256AuthenticationProvider-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=73 numCloses=73
   [junit4]   2> 600490 INFO  
(SUITE-TestSha256AuthenticationProvider-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 600491 INFO  
(SUITE-TestSha256AuthenticationProvider-seed#[82B64838C33BAD61]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): {}, 
docValues:{}, maxPointsInLeafNode=895, maxMBSortInHeap=5.45446445411749, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@268010b8),
 locale=bs-Cyrl-BA, timezone=America/Noronha
   [junit4]   2> NOTE: Linux 4.18.0-24-generic amd64/Aleksey Shipilev 
13-testing (64-bit)/cpus=8,threads=1,free=135348800,total=406323200
   [junit4]   2> NOTE: All tests run in this JVM: 
[ParsingFieldUpdateProcessorsTest, TestConfig, TestFieldCacheWithThreads, 
TestMergePolicyConfig, TestSimLargeCluster, TestManagedResource, 
TestXIncludeConfig, CustomTermsComponentTest, 
TestSolrCloudWithDelegationTokens, BaseCdcrDistributedZkTest, 
TestNumericRangeQuery32, SpellingQueryConverterTest, ConnectionReuseTest, 
TestSimExecutePlanAction, TestIndexSearcher, TestCloudPivotFacet, 
DisMaxRequestHandlerTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
DistributedFacetPivotWhiteBoxTest, DistributedQueryComponentOptimizationTest, 
TestUtilizeNode, ResponseLogComponentTest, CustomCollectionTest, 
TestSubQueryTransformer, DistributedSuggestComponentTest, 
TestFuzzyAnalyzedSuggestions, TestExpandComponent, CreateRoutedAliasTest, 
PeerSyncTest, TestDirectoryFactory, SolrShardReporterTest, 
RecoveryAfterSoftCommitTest, FullSolrCloudDistribCmdsTest, 
TestCursorMarkWithoutUniqueKey, NodeAddedTriggerIntegrationTest, 
MultiDestinationAuditLoggerTest, TestTestInjection, 
TestSerializedLuceneMatchVersion, 
PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestConfigReload, 
TestQueryUtils, TestRequestForwarding, TestBackupRepositoryFactory, 
TestQuerySenderNoQuery, ResponseHeaderTest, RemoteQueryErrorTest, 
ShowFileRequestHandlerTest, TestPrepRecovery, PrimitiveFieldTypeTest, 
MoveReplicaHDFSTest, TestManagedSynonymFilterFactory, TaggerTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, AuditLoggerPluginTest, 
OverseerTaskQueueTest, DistribJoinFromCollectionTest, CoreSorterTest, 
TermVectorComponentTest, ClassificationUpdateProcessorFactoryTest, 
TestZkChroot, OverseerModifyCollectionTest, TestJavabinTupleStreamParser, 
TestShortCircuitedRequests, DeleteNodeTest, TestSortableTextField, 
TestMinHashQParser, TestSortByMinMaxFunction, TestHighlightDedupGrouping, 
TestDocTermOrdsUninvertLimit, ReplicationFactorTest, BasicFunctionalityTest, 
StatsReloadRaceTest, ClusterStateTest, TestCloudRecovery2, 
TestPerFieldSimilarityWithDefaultOverride, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, DirectSolrConnectionTest, 
DocValuesNotIndexedTest, ImplicitSnitchTest, TestInitParams, 
TestTrackingShardHandlerFactory, LegacyCloudClusterPropTest, 
TestElisionMultitermQuery, CollectionStateFormat2Test, HLLUtilTest, 
HdfsWriteToMultipleCollectionsTest, TestManagedSchemaAPI, TestRecovery, 
MinimalSchemaTest, TestSimComputePlanAction, ZkControllerTest, 
AsyncCallRequestStatusResponseTest, TestSolrCloudWithHadoopAuthPlugin, 
TriggerIntegrationTest, TransactionLogTest, TestSolrCoreSnapshots, 
CurrencyRangeFacetCloudTest, TestRandomFaceting, NotRequiredUniqueKeyTest, 
SimpleFacetsTest, ExecutePlanActionTest, TestSafeXMLParsing, 
SearchRateTriggerIntegrationTest, SolrCoreMetricManagerTest, 
TestConfigSetProperties, SliceStateTest, TestFieldCacheSort, 
UnloadDistributedZkTest, TestPointFields, V2StandaloneTest, TestComponentsName, 
TestLuceneMatchVersion, SortSpecParsingTest, 
TestEmbeddedSolrServerConstructors, TestStressReorder, 
TestSha256AuthenticationProvider, TestSolrQueryResponse]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrQueryResponse -Dtests.seed=82B64838C33BAD61 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=bs-Cyrl-BA 
-Dtests.timezone=America/Noronha -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J1 | TestSolrQueryResponse (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: The test or suite 
printed 137148 bytes to stdout and stderr, even though the limit was set to 
8192 bytes. Increase the limit with @Limit, ignore it completely with 
@SuppressSysoutChecks or run with -Dtests.verbose=true
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([82B64838C33BAD61]:0)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:830)
   [junit4] Completed [352/871 (2!)] on J1 in 1.01s, 14 tests, 1 failure <<< 
FAILURES!

[...truncated 46639 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24434/consoleText

[repro] Revision: 7f04604d56f0e2b5fdf5729e8634d5a12c204ac2

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line:  ant test  -Dtestcase=MetricsHistoryWithAuthIntegrationTest 
-Dtests.method=testValuesAreCollected -Dtests.seed=82B64838C33BAD61 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hi 
-Dtests.timezone=Asia/Calcutta -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[repro] Repro line:  ant test  -Dtestcase=TestSolrQueryResponse 
-Dtests.seed=82B64838C33BAD61 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=bs-Cyrl-BA -Dtests.timezone=America/Noronha -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       TestSolrQueryResponse
[repro]       MetricsHistoryWithAuthIntegrationTest
[repro] ant compile-test

[...truncated 3336 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 
-Dtests.class="*.TestSolrQueryResponse|*.MetricsHistoryWithAuthIntegrationTest" 
-Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" 
-Dtests.seed=82B64838C33BAD61 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=bs-Cyrl-BA -Dtests.timezone=America/Noronha -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[...truncated 160 lines...]
[repro] Failures:
[repro]   0/5 failed: 
org.apache.solr.cloud.MetricsHistoryWithAuthIntegrationTest
[repro]   0/5 failed: org.apache.solr.response.TestSolrQueryResponse
[repro] Exiting with code 0

[...truncated 43 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to