You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/11/07 20:31:30 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 730 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/730/
1 tests failed.
FAILED: org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica
Error Message:
Error from server at http://127.0.0.1:42807/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:42807/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
at __randomizedtesting.SeedInfo.seed([ABCA223636E5832F:A3FDC4FEED4BF7A1]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1173)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica(TestPullReplicaErrorHandling.java:149)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15141 lines...]
[junit4] Suite: org.apache.solr.cloud.TestPullReplicaErrorHandling
[junit4] 2> 1602868 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1602868 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1602868 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/data-dir-128-001
[junit4] 2> 1602868 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1602869 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1602870 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1602871 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001
[junit4] 2> 1602871 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1602871 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1602871 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1602872 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1602971 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 43989
[junit4] 2> 1602972 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:43989
[junit4] 2> 1602972 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43989
[junit4] 2> 1602972 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43989
[junit4] 2> 1602974 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1602976 INFO (zkConnectionManagerCallback-14638-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602976 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1602979 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1602980 INFO (zkConnectionManagerCallback-14640-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602980 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1602981 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1602985 INFO (zkConnectionManagerCallback-14642-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602985 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603087 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1603087 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1603087 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1603087 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1603088 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1603097 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1603097 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1603097 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1603100 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1603100 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1603100 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1603100 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@46d8b4d7{/solr,null,AVAILABLE}
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1603103 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1965bc38{/solr,null,AVAILABLE}
[junit4] 2> 1603104 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f9b3149{/solr,null,AVAILABLE}
[junit4] 2> 1603104 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@164f80be{/solr,null,AVAILABLE}
[junit4] 2> 1603104 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@63598e15{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36641}
[junit4] 2> 1603104 INFO (jetty-launcher-14643-thread-1) [ ] o.e.j.s.Server Started @1603130ms
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36641}
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6786d809{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:38639}
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.e.j.s.Server Started @1603131ms
[junit4] 2> 1603105 ERROR (jetty-launcher-14643-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@62a25ec{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:41451}
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38639}
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.e.j.s.Server Started @1603131ms
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41451}
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:23.206Z
[junit4] 2> 1603105 ERROR (jetty-launcher-14643-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1603105 ERROR (jetty-launcher-14643-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:23.206Z
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60f7dff7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33609}
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.e.j.s.Server Started @1603131ms
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33609}
[junit4] 2> 1603105 ERROR (jetty-launcher-14643-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1603105 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:23.206Z
[junit4] 2> 1603106 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:23.207Z
[junit4] 2> 1603107 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1603108 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1603109 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1603117 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1603117 INFO (zkConnectionManagerCallback-14647-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603117 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603117 INFO (zkConnectionManagerCallback-14649-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603117 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603118 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1603118 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1603121 INFO (zkConnectionManagerCallback-14651-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603121 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603121 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1603122 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1603124 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1603125 INFO (zkConnectionManagerCallback-14645-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603125 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603125 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1603126 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1603126 WARN (jetty-launcher-14643-thread-2-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930006.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1603129 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1603130 WARN (jetty-launcher-14643-thread-1-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1603745 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1603749 WARN (jetty-launcher-14643-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5278e50c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1603749 WARN (jetty-launcher-14643-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5278e50c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1603754 WARN (jetty-launcher-14643-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7bd05f69[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1603754 WARN (jetty-launcher-14643-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7bd05f69[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1603755 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1603756 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1603757 INFO (zkConnectionManagerCallback-14671-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603757 INFO (jetty-launcher-14643-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603859 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1603860 INFO (zkConnectionManagerCallback-14673-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603860 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1603911 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33609_solr
[junit4] 2> 1603911 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.Overseer Overseer (id=72439364597252104-127.0.0.1:33609_solr-n_0000000000) starting
[junit4] 2> 1603925 INFO (OverseerStateUpdate-72439364597252104-127.0.0.1:33609_solr-n_0000000000) [n:127.0.0.1:33609_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33609_solr
[junit4] 2> 1603933 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33609_solr
[junit4] 2> 1603937 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1603937 WARN (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1603949 INFO (zkCallback-14672-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1603960 INFO (jetty-launcher-14643-thread-2) [n:127.0.0.1:33609_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node2
[junit4] 2> 1604101 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1604101 WARN (jetty-launcher-14643-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@16615e9f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604104 WARN (jetty-launcher-14643-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@16615e9f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604109 WARN (jetty-launcher-14643-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2eee2e13[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604109 WARN (jetty-launcher-14643-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2eee2e13[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604110 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1604111 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1604113 INFO (zkConnectionManagerCallback-14691-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1604113 INFO (jetty-launcher-14643-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1604114 WARN (jetty-launcher-14643-thread-4-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930009.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930009, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930009, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1604125 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1604126 WARN (jetty-launcher-14643-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@623f393d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604126 WARN (jetty-launcher-14643-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@623f393d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604130 WARN (jetty-launcher-14643-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@687c06ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604130 WARN (jetty-launcher-14643-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@687c06ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604131 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1604132 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1604132 INFO (zkConnectionManagerCallback-14699-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1604132 INFO (jetty-launcher-14643-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1604216 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1604217 INFO (zkConnectionManagerCallback-14701-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1604217 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1604224 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1604228 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41451_solr as DOWN
[junit4] 2> 1604229 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1604229 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41451_solr
[junit4] 2> 1604230 INFO (zkCallback-14672-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1604230 INFO (zkCallback-14700-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1604234 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1604235 WARN (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1604257 INFO (jetty-launcher-14643-thread-4) [n:127.0.0.1:41451_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node4
[junit4] 2> 1604315 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1604317 INFO (zkConnectionManagerCallback-14709-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1604317 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1604322 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1604326 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36641_solr as DOWN
[junit4] 2> 1604327 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1604327 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36641_solr
[junit4] 2> 1604328 INFO (zkCallback-14700-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1604328 INFO (zkCallback-14672-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1604333 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1604334 WARN (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1604337 INFO (zkCallback-14708-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1604349 INFO (jetty-launcher-14643-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node1
[junit4] 2> 1604915 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1604917 WARN (jetty-launcher-14643-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2118e0cf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604917 WARN (jetty-launcher-14643-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2118e0cf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604925 WARN (jetty-launcher-14643-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@44fb2ddd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604925 WARN (jetty-launcher-14643-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@44fb2ddd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1604926 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1604927 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1604928 INFO (zkConnectionManagerCallback-14723-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1604928 INFO (jetty-launcher-14643-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1604929 WARN (jetty-launcher-14643-thread-3-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e293000d.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e293000d, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e293000d, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1605030 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1605031 INFO (zkConnectionManagerCallback-14725-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1605031 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1605035 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1605038 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38639_solr as DOWN
[junit4] 2> 1605038 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1605038 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38639_solr
[junit4] 2> 1605040 INFO (zkCallback-14672-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605040 INFO (zkCallback-14708-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605040 INFO (zkCallback-14700-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605040 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605042 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1605042 WARN (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1605060 INFO (jetty-launcher-14643-thread-3) [n:127.0.0.1:38639_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node3
[junit4] 2> 1605069 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1605072 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1605073 INFO (zkConnectionManagerCallback-14736-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1605073 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1605075 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1605076 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43989/solr ready
[junit4] 2> 1605078 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=375799483
[junit4] 2> 1605078 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33609_solr
[junit4] 2> 1605078 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1605078 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:33609_solr as DOWN
[junit4] 2> 1605082 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1605085 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1605085 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1605085 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1605085 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1605107 INFO (closeThreadPool-14741-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72439364597252104-127.0.0.1:33609_solr-n_0000000000) closing
[junit4] 2> 1605109 INFO (OverseerStateUpdate-72439364597252104-127.0.0.1:33609_solr-n_0000000000) [n:127.0.0.1:33609_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:33609_solr
[junit4] 2> 1605116 INFO (OverseerAutoScalingTriggerThread-72439364597252104-127.0.0.1:33609_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1605117 INFO (closeThreadPool-14741-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72439364597252104-127.0.0.1:33609_solr-n_0000000000) closing
[junit4] 2> 1605118 WARN (jetty-launcher-14643-thread-2-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1605118 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605118 INFO (zkCallback-14708-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605118 INFO (zkCallback-14700-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41451_solr
[junit4] 2> 1605119 INFO (zkCallback-14700-thread-1) [n:127.0.0.1:41451_solr ] o.a.s.c.Overseer Overseer (id=72439364597252107-127.0.0.1:41451_solr-n_0000000001) starting
[junit4] 2> 1605121 INFO (zkCallback-14735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605122 INFO (zkCallback-14700-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605126 INFO (OverseerStateUpdate-72439364597252107-127.0.0.1:41451_solr-n_0000000001) [n:127.0.0.1:41451_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41451_solr
[junit4] 2> 1605219 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.Overseer Overseer (id=72439364597252104-127.0.0.1:33609_solr-n_0000000000) closing
[junit4] 2> 1605219 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@60f7dff7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1605220 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@f9b3149{/solr,null,STOPPED}
[junit4] 2> 1605220 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1605221 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1605221 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1605221 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@560753a4{/solr,null,AVAILABLE}
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@48193537{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34677}
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.Server Started @1605248ms
[junit4] 2> 1605222 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42807}
[junit4] 2> 1605223 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1605223 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1605223 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1605223 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1605223 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1605223 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:25.324Z
[junit4] 2> 1605225 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1605229 INFO (zkConnectionManagerCallback-14747-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1605229 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1605230 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1605235 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1605236 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930010.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930010, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930010, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1605628 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1605629 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@b688432[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1605629 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@b688432[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1605632 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2277a1c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1605632 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2277a1c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1605633 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1605635 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1605637 INFO (zkConnectionManagerCallback-14758-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1605637 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1605740 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1605741 INFO (zkConnectionManagerCallback-14760-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1605741 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1605747 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1605751 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42807_solr as DOWN
[junit4] 2> 1605753 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1605753 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42807_solr
[junit4] 2> 1605754 INFO (zkCallback-14700-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605754 INFO (zkCallback-14735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605754 INFO (zkCallback-14708-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605754 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605755 INFO (zkCallback-14759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1605756 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1605757 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1605782 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42807_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node2
[junit4] 2> 1605790 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1605791 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:34677/solr. Proxy: http://127.0.0.1:42807/solr
[junit4] 2> 1605791 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1287975036
[junit4] 2> 1605791 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41451_solr
[junit4] 2> 1605792 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1605792 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:41451_solr as DOWN
[junit4] 2> 1605795 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1605795 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1605795 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1605795 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1605796 INFO (closeThreadPool-14771-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72439364597252107-127.0.0.1:41451_solr-n_0000000001) closing
[junit4] 2> 1605796 INFO (OverseerStateUpdate-72439364597252107-127.0.0.1:41451_solr-n_0000000001) [n:127.0.0.1:41451_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41451_solr
[junit4] 2> 1605798 INFO (OverseerAutoScalingTriggerThread-72439364597252107-127.0.0.1:41451_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1605800 INFO (closeThreadPool-14771-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72439364597252107-127.0.0.1:41451_solr-n_0000000001) closing
[junit4] 2> 1605800 INFO (zkCallback-14735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605801 WARN (jetty-launcher-14643-thread-4-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e293000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e293000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e293000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1605802 INFO (zkCallback-14759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605802 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605802 INFO (zkCallback-14708-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1605802 INFO (zkCallback-14708-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36641_solr
[junit4] 2> 1605806 INFO (zkCallback-14708-thread-1) [n:127.0.0.1:36641_solr ] o.a.s.c.Overseer Overseer (id=72439364597252108-127.0.0.1:36641_solr-n_0000000002) starting
[junit4] 2> 1605810 INFO (OverseerStateUpdate-72439364597252108-127.0.0.1:36641_solr-n_0000000002) [n:127.0.0.1:36641_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:36641_solr
[junit4] 2> 1605902 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.Overseer Overseer (id=72439364597252107-127.0.0.1:41451_solr-n_0000000001) closing
[junit4] 2> 1605903 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@62a25ec{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1605904 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@164f80be{/solr,null,STOPPED}
[junit4] 2> 1605904 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1605904 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1605904 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1605904 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1605909 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1605909 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1605909 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1605910 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@46be159f{/solr,null,AVAILABLE}
[junit4] 2> 1605910 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1696fa8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:38895}
[junit4] 2> 1605910 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.Server Started @1605936ms
[junit4] 2> 1605910 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42943}
[junit4] 2> 1605910 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1605911 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1605911 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1605911 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1605911 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1605911 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:26.012Z
[junit4] 2> 1605914 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1605917 INFO (zkConnectionManagerCallback-14777-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1605917 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1605918 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1605926 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1605927 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930013.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930013, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930013, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1607150 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1607150 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@f842d27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607150 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@f842d27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607154 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c3c6fc5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607154 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c3c6fc5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607155 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1607156 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1607156 INFO (zkConnectionManagerCallback-14788-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1607156 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1607157 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930014.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930014, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930014, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1607259 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1607260 INFO (zkConnectionManagerCallback-14790-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1607260 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1607264 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1607267 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42943_solr as DOWN
[junit4] 2> 1607268 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1607268 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42943_solr
[junit4] 2> 1607269 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1607269 INFO (zkCallback-14735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1607270 INFO (zkCallback-14759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1607270 INFO (zkCallback-14708-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1607273 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1607273 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1607274 INFO (zkCallback-14789-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1607291 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:42943_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node4
[junit4] 2> 1607300 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1607300 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:38895/solr. Proxy: http://127.0.0.1:42943/solr
[junit4] 2> 1607300 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=236083887
[junit4] 2> 1607300 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36641_solr
[junit4] 2> 1607301 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1607301 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:36641_solr as DOWN
[junit4] 2> 1607306 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1607306 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1607306 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1607307 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1607307 INFO (closeThreadPool-14801-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72439364597252108-127.0.0.1:36641_solr-n_0000000002) closing
[junit4] 2> 1607309 INFO (OverseerStateUpdate-72439364597252108-127.0.0.1:36641_solr-n_0000000002) [n:127.0.0.1:36641_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:36641_solr
[junit4] 2> 1607309 INFO (OverseerAutoScalingTriggerThread-72439364597252108-127.0.0.1:36641_solr-n_0000000002) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1607315 INFO (closeThreadPool-14801-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72439364597252108-127.0.0.1:36641_solr-n_0000000002) closing
[junit4] 2> 1607316 INFO (zkCallback-14735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1607316 INFO (zkCallback-14759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1607317 INFO (zkCallback-14789-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1607318 WARN (jetty-launcher-14643-thread-1-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e293000c.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e293000c, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e293000c, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1607318 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38639_solr
[junit4] 2> 1607318 INFO (zkCallback-14724-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1607419 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.Overseer Overseer (id=72439364597252108-127.0.0.1:36641_solr-n_0000000002) closing
[junit4] 2> 1607420 INFO (zkCallback-14724-thread-1) [n:127.0.0.1:38639_solr ] o.a.s.c.Overseer Overseer (id=72439364597252110-127.0.0.1:38639_solr-n_0000000003) starting
[junit4] 2> 1607420 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@63598e15{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1607420 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1965bc38{/solr,null,STOPPED}
[junit4] 2> 1607420 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1607421 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1607421 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1607421 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 1.8.0_252-b09
[junit4] 2> 1607422 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1607422 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1607422 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1607422 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a843fef{/solr,null,AVAILABLE}
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@46f3f507{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:44103}
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.s.Server Started @1607451ms
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38337}
[junit4] 2> 1607425 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1607425 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-07T19:59:27.526Z
[junit4] 2> 1607427 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1607428 INFO (OverseerStateUpdate-72439364597252110-127.0.0.1:38639_solr-n_0000000003) [n:127.0.0.1:38639_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38639_solr
[junit4] 2> 1607428 INFO (zkConnectionManagerCallback-14803-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1607428 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1607428 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1607433 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1607975 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1607976 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@29bf0fc1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607976 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@29bf0fc1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607979 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@470ace1e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607979 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@470ace1e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1607980 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43989/solr
[junit4] 2> 1607981 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1607986 INFO (zkConnectionManagerCallback-14818-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1607986 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1608088 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1608089 INFO (zkConnectionManagerCallback-14820-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1608089 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1608093 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1608096 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38337_solr as DOWN
[junit4] 2> 1608097 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1608097 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38337_solr
[junit4] 2> 1608098 INFO (zkCallback-14789-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1608098 INFO (zkCallback-14735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1608098 INFO (zkCallback-14759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1608098 INFO (zkCallback-14724-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1608098 INFO (zkCallback-14819-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1608099 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1608099 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1608116 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [n:127.0.0.1:38337_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001/tempDir-001/node1
[junit4] 2> 1608124 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1608124 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F
[...truncated too long message...]
r-14907-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1696fa8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1640879 INFO (jetty-closer-14907-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@46be159f{/solr,null,STOPPED}
[junit4] 2> 1640879 INFO (jetty-closer-14907-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1640967 INFO (jetty-closer-14907-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72439364597252114-127.0.0.1:42807_solr-n_0000000004) closing
[junit4] 2> 1640969 INFO (jetty-closer-14907-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@48193537{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1640970 INFO (jetty-closer-14907-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@560753a4{/solr,null,STOPPED}
[junit4] 2> 1640970 INFO (jetty-closer-14907-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1641070 INFO (jetty-closer-14907-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6823706a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1641071 INFO (jetty-closer-14907-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3f92c523{/solr,null,STOPPED}
[junit4] 2> 1641071 INFO (jetty-closer-14907-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1641072 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1641175 WARN (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker-SendThread(127.0.0.1:43989)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1015b37e2930001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1015b37e2930001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1641276 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> 14 /solr/aliases.json
[junit4] 2> 13 /solr/security.json
[junit4] 2> 11 /solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/terms/shard1
[junit4] 2> 10 /solr/configs/conf
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 8 /solr/packages.json
[junit4] 2> 7 /solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/terms/shard1
[junit4] 2> 6 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/terms/shard1
[junit4] 2> 6 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json
[junit4] 2> 5 /solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json
[junit4] 2> 2 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/collectionprops.json
[junit4] 2> 2 /solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 53 /solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/state.json
[junit4] 2> 37 /solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json
[junit4] 2> 23 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json
[junit4] 2> 14 /solr/clusterstate.json
[junit4] 2> 10 /solr/clusterprops.json
[junit4] 2> 5 /solr/overseer_elect/election/72439364597252123-127.0.0.1:43363_solr-n_0000000007
[junit4] 2> 5 /solr/autoscaling.json
[junit4] 2> 2 /solr/overseer_elect/election/72439364597252114-127.0.0.1:42807_solr-n_0000000004
[junit4] 2> 2 /solr/overseer_elect/election/72439364597252117-127.0.0.1:42943_solr-n_0000000005
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 109 /solr/live_nodes
[junit4] 2> 101 /solr/overseer/queue
[junit4] 2> 57 /solr/collections
[junit4] 2> 27 /solr/overseer/collection-queue-work
[junit4] 2> 5 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 5 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 5 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 1641279 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43989
[junit4] 2> 1641279 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43989
[junit4] 2> 1641279 INFO (SUITE-TestPullReplicaErrorHandling-seed#[ABCA223636E5832F]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43989
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplicaErrorHandling_ABCA223636E5832F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {_version_=FST50, _root_=FST50, fieldName_s=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=534, maxMBSortInHeap=6.956578622217865, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=de-GR, timezone=America/Noronha
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=199704560,total=522190848
[junit4] 2> NOTE: All tests run in this JVM: [BinaryUpdateRequestHandlerTest, TestStressThreadBackup, MetricTriggerTest, BasicFunctionalityTest, DistanceUnitsTest, AnalyticsQueryTest, ProtectedTermFilterFactoryTest, HttpPartitionOnCommitTest, IndexSizeTriggerMixedBoundsTest, TestLRUCache, TestSurroundQueryParser, LargeFieldTest, TestContainerPlugin, TestFieldCollectionResource, ComputePlanActionTest, TestHighFrequencyDictionaryFactory, SolrCloudExampleTest, TestJavabinTupleStreamParser, CachingDirectoryFactoryTest, UpdateLogTest, TestCloudInspectUtil, TestCollectionsAPIViaSolrCloudCluster, TestBulkSchemaConcurrent, TestSimGenericDistributedQueue, TestLRUStatsCache, TestLeaderElectionWithEmptyReplica, CoreMergeIndexesAdminHandlerTest, TestConfigSetsAPI, UUIDUpdateProcessorFallbackTest, MetricsHistoryWithAuthIntegrationTest, TestMacros, SpatialHeatmapFacetsTest, DeleteReplicaTest, CdcrWithNodesRestartsTest, TestShardHandlerFactory, TestPartialUpdateDeduplication, HdfsRecoveryZkTest, CdcrOpsAndBoundariesTest, ScheduledTriggerIntegrationTest, TestSimDistribStateManager, SolrLogAuditLoggerPluginTest, TestCryptoKeys, TestBlendedInfixSuggestions, TestPayloadCheckQParserPlugin, ConjunctionSolrSpellCheckerTest, TestSchemaManager, TestJsonFacetsWithNestedObjects, ReplicationFactorTest, TestSizeLimitedDistributedMap, TestConfigSetImmutable, DistributedFacetExistsSmallTest, TestHashQParserPlugin, ZookeeperStatusHandlerTest, DateRangeFieldTest, EnumFieldTest, TestMinMaxOnMultiValuedField, TestRequestStatusCollectionAPI, SearchHandlerTest, TestXmlQParser, NodeLostTriggerTest, HighlighterMaxOffsetTest, MetricsHandlerTest, TestReplicationHandlerBackup, DocValuesNotIndexedTest, CreateRoutedAliasTest, AlternateDirectoryTest, SOLR749Test, TestPerFieldSimilarity, TaggingAttributeTest, TestSolrCoreProperties, LeaderTragicEventTest, TestSha256AuthenticationProvider, TestBadConfig, XmlUpdateRequestHandlerTest, MoveReplicaHDFSTest, PeerSyncWithIndexFingerprintCachingTest, ChangedSchemaMergeTest, SolrGraphiteReporterTest, TestFacetMethods, TestSubQueryTransformerDistrib, BJQParserTest, CollectionReloadTest, TestQueryingOnDownCollection, TestElisionMultitermQuery, OrderedExecutorTest, SoftAutoCommitTest, ImplicitSnitchTest, TestPivotHelperCode, DistribDocExpirationUpdateProcessorTest, TestOnReconnectListenerSupport, TestRequestForwarding, TestIndexingPerformance, TestJsonFacetErrors, WrapperMergePolicyFactoryTest, ChaosMonkeyNothingIsSafeTest, TestMaxTokenLenTokenizer, DefaultValueUpdateProcessorTest, TestSimPolicyCloud, TestCoreDiscovery, TestConfigsApi, CrossCollectionJoinQueryTest, SuggestComponentContextFilterQueryTest, TestCSVResponseWriter, ZkShardTermsTest, SuggesterTest, ShardRoutingTest, SolrIndexSplitterTest, TestDynamicLoadingUrl, TestTlogReplayVsRecovery, TestManagedSchemaThreadSafety, StatsReloadRaceTest, AddReplicaTest, AssignBackwardCompatibilityTest, CollectionPropsTest, CreateCollectionCleanupTest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, MoveReplicaHDFSFailoverTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerSolrResponseTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, OverseerTaskQueueTest, PeerSyncReplicationTest, TestCloudRecovery2, TestClusterProperties, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestPullReplicaErrorHandling]
[junit4] Completed [547/921 (1!)] on J1 in 38.67s, 4 tests, 1 error <<< FAILURES!
[...truncated 51856 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 731 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/731/
1 tests failed.
FAILED: org.apache.solr.TestRandomDVFaceting.testRandomFaceting
Error Message:
mismatch: 'k'!='j' @ facet_counts/facet_fields/small4_ss/[6]
Stack Trace:
java.lang.AssertionError: mismatch: 'k'!='j' @ facet_counts/facet_fields/small4_ss/[6]
at __randomizedtesting.SeedInfo.seed([DCB4BC7ED3EC8D76:D1DC9CAB861545C9]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.apache.solr.TestRandomDVFaceting.doFacetTests(TestRandomDVFaceting.java:292)
at org.apache.solr.TestRandomDVFaceting.doFacetTests(TestRandomDVFaceting.java:175)
at org.apache.solr.TestRandomDVFaceting.testRandomFaceting(TestRandomDVFaceting.java:158)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14641 lines...]
[junit4] Suite: org.apache.solr.TestRandomDVFaceting
[junit4] 2> 1052395 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1052395 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1052395 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.TestRandomDVFaceting_DCB4BC7ED3EC8D76-001/data-dir-138-001
[junit4] 2> 1052395 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=312 numCloses=312
[junit4] 2> 1052395 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1052397 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1052397 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1052403 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 1052417 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1052422 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1052601 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_252]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_252]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_252]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_252]
[junit4] 2> at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) ~[?:?]
[junit4] 2> at sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376) ~[?:?]
[junit4] 2> at java.nio.file.Files.createDirectory(Files.java:674) ~[?:1.8.0_252]
[junit4] 2> at java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_252]
[junit4] 2> at java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_252]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:371) [java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:336) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:806) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:796) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:646) [java/:?]
[junit4] 2> at org.apache.solr.TestRandomDVFaceting.beforeTests(TestRandomDVFaceting.java:55) [test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_252]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_252]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_252]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_252]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
[junit4] 2> 1052602 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_252]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_252]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_252]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_252]
[junit4] 2> at java.io.File.mkdir(File.java:1311) ~[?:1.8.0_252]
[junit4] 2> at java.io.File.mkdirs(File.java:1343) ~[?:1.8.0_252]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561) [java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81) [java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) [java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:672) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:171) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:806) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:796) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:646) [java/:?]
[junit4] 2> at org.apache.solr.TestRandomDVFaceting.beforeTests(TestRandomDVFaceting.java:55) [test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_252]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_252]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_252]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_252]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
[junit4] 2> 1052603 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1052604 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@16eca620[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1052604 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@16eca620[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1052607 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@282a6d2d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1052607 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@282a6d2d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1052608 WARN (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1052635 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29241039
[junit4] 2> 1052641 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29241039
[junit4] 2> 1052641 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29241039
[junit4] 2> 1052642 INFO (coreLoadExecutor-8669-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1052651 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 1052660 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1052667 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1052667 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1052667 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29241039
[junit4] 2> 1052668 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.TestRandomDVFaceting_DCB4BC7ED3EC8D76-001/data-dir-138-001/]
[junit4] 2> 1052668 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=49.5224609375, floorSegmentMB=2.0048828125, forceMergeDeletesPctAllowed=17.573604167644845, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5403357297668139, deletesPctAllowed=25.49569987866973
[junit4] 2> 1052707 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1052707 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1052708 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=4, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=54.478515625, floorSegmentMB=0.3818359375, forceMergeDeletesPctAllowed=1.4750074801195345, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3612634177613461, deletesPctAllowed=26.731412930140035
[junit4] 2> 1052708 WARN (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/conf ; switching to use InMemory storage instead.
[junit4] 2> 1052709 INFO (coreLoadExecutor-8669-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1052711 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1052711 INFO (searcherExecutor-8671-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1052716 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomFaceting
[junit4] 2> 1052716 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1052717 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 1052719 INFO (searcherExecutor-8671-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1052720 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 1052721 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&indent=true&fl=id&sort=_docid_+asc&rows=0&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 1052727 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=1&facet.limit=1&facet.prefix=&facet.field=id&facet.method=enum} hits=0 status=0 QTime=5
[junit4] 2> 1052728 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=1&facet.limit=1&facet.prefix=&facet.field=id&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052747 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=1&facet.limit=1&facet.prefix=&facet.field=id&facet.method=fcs} hits=0 status=0 QTime=18
[junit4] 2> 1052747 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=1&facet.limit=1&facet.prefix=&facet.field={!key%3Did}id_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052747 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=1&facet.limit=1&facet.prefix=&facet.field=id&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052747 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_f&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052748 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_f&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052748 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_f&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052748 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field={!key%3Dscore_f}score_f_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052749 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_f&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052749 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field={!key%3Dscore_f}score_f_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052749 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=1&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052749 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=1&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052749 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=1&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=1&facet.sort=count&facet.mincount=2&facet.field={!key%3Dscore_d}score_d_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=1&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=1&facet.sort=count&facet.mincount=2&facet.field={!key%3Dscore_d}score_d_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=foo_i&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=foo_i&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=foo_i&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052750 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field={!key%3Dfoo_i}foo_i_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052751 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=foo_i&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052751 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field={!key%3Dfoo_i}foo_i_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052752 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=3&facet.field=foo_l&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052752 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=3&facet.field=foo_l&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052752 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=3&facet.field=foo_l&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052752 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=3&facet.field={!key%3Dfoo_l}foo_l_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052752 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=3&facet.field=foo_l&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052753 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=3&facet.field={!key%3Dfoo_l}foo_l_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052753 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=small_s&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052753 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=small_s&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052753 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=small_s&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052753 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field={!key%3Dsmall_s}small_s_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052753 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=small_s&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052754 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=5&facet.prefix=&facet.missing=true&facet.field=small2_s&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052754 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=5&facet.prefix=&facet.missing=true&facet.field=small2_s&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052754 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=5&facet.prefix=&facet.missing=true&facet.field=small2_s&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052754 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=5&facet.prefix=&facet.missing=true&facet.field={!key%3Dsmall2_s}small2_s_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052754 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=5&facet.prefix=&facet.missing=true&facet.field=small2_s&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052755 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=index&facet.mincount=2&facet.missing=true&facet.field=small2_ss&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052755 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=index&facet.mincount=2&facet.missing=true&facet.field=small2_ss&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052755 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=index&facet.mincount=2&facet.missing=true&facet.field=small2_ss&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052755 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=index&facet.mincount=2&facet.missing=true&facet.field={!key%3Dsmall2_ss}small2_ss_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052755 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=small2_ss,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1052755 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=index&facet.mincount=2&facet.missing=true&facet.field=small2_ss&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052756 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.prefix=B&facet.field=small3_ss&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052756 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.prefix=B&facet.field=small3_ss&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052756 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.prefix=B&facet.field=small3_ss&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052756 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.prefix=B&facet.field={!key%3Dsmall3_ss}small3_ss_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052756 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.prefix=B&facet.field=small3_ss&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052756 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small4_ss&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small4_ss&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small4_ss&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field={!key%3Dsmall4_ss}small4_ss_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small4_ss&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=small_i&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=small_i&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052757 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=small_i&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052758 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field={!key%3Dsmall_i}small_i_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052758 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=small_i&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052758 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field={!key%3Dsmall_i}small_i_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052758 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=small2_i&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052759 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=small2_i&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052759 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=small2_i&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052759 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field={!key%3Dsmall2_i}small2_i_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052759 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=small2_i&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052760 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field={!key%3Dsmall2_i}small2_i_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052760 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=0&facet.field=small2_is&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052760 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=0&facet.field=small2_is&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052760 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=0&facet.field=small2_is&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052760 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=0&facet.field={!key%3Dsmall2_is}small2_is_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052760 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=0&facet.field=small2_is&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.missing=true&facet.field=small3_is&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.missing=true&facet.field=small3_is&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.missing=true&facet.field=small3_is&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.missing=true&facet.field={!key%3Dsmall3_is}small3_is_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.missing=true&facet.field=small3_is&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=3&facet.sort=index&facet.field=foo_fs&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052761 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=3&facet.sort=index&facet.field=foo_fs&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052762 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=3&facet.sort=index&facet.field=foo_fs&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052762 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=3&facet.sort=index&facet.field={!key%3Dfoo_fs}foo_fs_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052762 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.limit=3&facet.sort=index&facet.field=foo_fs&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052762 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_f&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052762 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_f&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052762 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_f&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field={!key%3Dfoo_f}foo_f_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_f&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field={!key%3Dfoo_f}foo_f_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.missing=true&facet.field=foo_ds&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.missing=true&facet.field=foo_ds&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.missing=true&facet.field=foo_ds&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052763 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.missing=true&facet.field={!key%3Dfoo_ds}foo_ds_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052764 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.missing=true&facet.field=foo_ds&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052764 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=4&facet.field=foo_d&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052764 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=4&facet.field=foo_d&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052764 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=4&facet.field=foo_d&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052764 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=4&facet.field={!key%3Dfoo_d}foo_d_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052764 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=4&facet.field=foo_d&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.mincount=4&facet.field={!key%3Dfoo_d}foo_d_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=foo_ls&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=foo_ls&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=foo_ls&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field={!key%3Dfoo_ls}foo_ls_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.field=foo_ls&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052765 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=missing_i&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052766 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=missing_i&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052766 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=missing_i&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052766 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field={!key%3Dmissing_i}missing_i_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field=missing_i&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=1&facet.field={!key%3Dmissing_i}missing_i_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=missing_is&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=missing_is&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=missing_is&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field={!key%3Dmissing_is}missing_is_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052767 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=missing_is,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=missing_is&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field={!key%3Dmissing_is}missing_is_p&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.prefix=&facet.missing=true&facet.field=missing_s&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.prefix=&facet.missing=true&facet.field=missing_s&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.prefix=&facet.missing=true&facet.field=missing_s&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.prefix=&facet.missing=true&facet.field={!key%3Dmissing_s}missing_s_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052768 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.prefix=&facet.missing=true&facet.field=missing_s&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052769 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.mincount=4&facet.prefix=½&facet.field=missing_ss&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1052769 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.mincount=4&facet.prefix=½&facet.field=missing_ss&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1052769 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.mincount=4&facet.prefix=½&facet.field=missing_ss&facet.method=fcs} hits=0 status=0 QTime=0
[junit4] 2> 1052769 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.mincount=4&facet.prefix=½&facet.field={!key%3Dmissing_ss}missing_ss_dv} hits=0 status=0 QTime=0
[junit4] 2> 1052779 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=missing_ss,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1052779 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.mincount=4&facet.prefix=½&facet.field=missing_ss&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1052780 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[ZHXO]} 0 1
[junit4] 2> 1052781 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[UNNA]} 0 0
[junit4] 2> 1052782 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[TKAU]} 0 0
[junit4] 2> 1052791 INFO (searcherExecutor-8671-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1052791 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 1052792 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1052792 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&indent=true&fl=id&sort=_docid_+asc&rows=6&wt=json} hits=3 status=0 QTime=0
[junit4] 2> 1052792 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=id:(ZHXO TKAU )} 0 0
[junit4] 2> 1052796 INFO (searcherExecutor-8671-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1052796 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1052800 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=id&facet.method=enum} hits=1 status=0 QTime=2
[junit4] 2> 1052801 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=id&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052807 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=id&facet.method=fcs} hits=1 status=0 QTime=6
[junit4] 2> 1052807 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field={!key%3Did}id_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052807 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=id&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052808 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=6&facet.sort=count&facet.mincount=1&facet.field=score_f&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052808 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=6&facet.sort=count&facet.mincount=1&facet.field=score_f&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052808 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=6&facet.sort=count&facet.mincount=1&facet.field=score_f&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052808 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=6&facet.sort=count&facet.mincount=1&facet.field={!key%3Dscore_f}score_f_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052809 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=6&facet.sort=count&facet.mincount=1&facet.field=score_f&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052809 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.limit=6&facet.sort=count&facet.mincount=1&facet.field={!key%3Dscore_f}score_f_p&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052809 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052809 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052809 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052809 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field={!key%3Dscore_d}score_d_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052810 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=score_d&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052810 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field={!key%3Dscore_d}score_d_p&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052810 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=foo_i&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052810 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=foo_i&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052810 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=foo_i&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052810 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field={!key%3Dfoo_i}foo_i_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052811 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field=foo_i&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052811 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=5&facet.field={!key%3Dfoo_i}foo_i_p&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052811 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_l&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052811 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_l&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052811 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_l&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052811 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field={!key%3Dfoo_l}foo_l_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052812 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field=foo_l&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052812 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=3&facet.missing=true&facet.field={!key%3Dfoo_l}foo_l_p&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052812 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.field=small_s&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052812 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.field=small_s&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052813 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.field=small_s&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052813 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.field={!key%3Dsmall_s}small_s_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052814 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.field=small_s&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052814 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.mincount=1&facet.field=small2_s&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052814 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.mincount=1&facet.field=small2_s&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052815 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.mincount=1&facet.field=small2_s&facet.method=fcs} hits=1 status=0 QTime=1
[junit4] 2> 1052815 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.mincount=1&facet.field={!key%3Dsmall2_s}small2_s_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052816 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.mincount=1&facet.field=small2_s&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052817 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small2_ss&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052817 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small2_ss&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052817 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small2_ss&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052817 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field={!key%3Dsmall2_ss}small2_ss_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052817 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small2_ss&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052817 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small3_ss&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052818 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small3_ss&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052818 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small3_ss&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052818 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field={!key%3Dsmall3_ss}small3_ss_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052818 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.field=small3_ss&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052818 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.prefix=&facet.field=small4_ss&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052819 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.prefix=&facet.field=small4_ss&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052819 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.prefix=&facet.field=small4_ss&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052819 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.prefix=&facet.field={!key%3Dsmall4_ss}small4_ss_dv} hits=1 status=0 QTime=0
[junit4] 2> 1052819 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=index&facet.prefix=&facet.field=small4_ss&facet.method=uif} hits=1 status=0 QTime=0
[junit4] 2> 1052819 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=small_i&facet.method=enum} hits=1 status=0 QTime=0
[junit4] 2> 1052819 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=small_i&facet.method=fc} hits=1 status=0 QTime=0
[junit4] 2> 1052820 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.sort=count&facet.mincount=2&facet.field=small_i&facet.method=fcs} hits=1 status=0 QTime=0
[junit4] 2> 1052820 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DC
[...truncated too long message...]
et_queries":{},
[junit4] 2> "facet_fields":{
[junit4] 2> "small4_ss":[
[junit4] 2> "w",3,
[junit4] 2> "c",2,
[junit4] 2> "g",2,
[junit4] 2> "k",2,
[junit4] 2> "l",2,
[junit4] 2> "q",2,
[junit4] 2> "r",2,
[junit4] 2> "v",2,
[junit4] 2> "a",1,
[junit4] 2> "b",1,
[junit4] 2> "e",1,
[junit4] 2> "h",1,
[junit4] 2> "j",1,
[junit4] 2> "m",1,
[junit4] 2> "n",1,
[junit4] 2> "o",1,
[junit4] 2> "s",1,
[junit4] 2> "t",1]},
[junit4] 2> "facet_ranges":{},
[junit4] 2> "facet_intervals":{},
[junit4] 2> "facet_heatmaps":{}}}
[junit4] 2>
[junit4] 2> response = {
[junit4] 2> "response":{"numFound":42,"start":0,"numFoundExact":true,"docs":[]
[junit4] 2> },
[junit4] 2> "facet_counts":{
[junit4] 2> "facet_queries":{},
[junit4] 2> "facet_fields":{
[junit4] 2> "small4_ss":[
[junit4] 2> "w",3,
[junit4] 2> "c",2,
[junit4] 2> "g",2,
[junit4] 2> "j",2,
[junit4] 2> "k",2,
[junit4] 2> "l",2,
[junit4] 2> "q",2,
[junit4] 2> "r",2,
[junit4] 2> "v",2,
[junit4] 2> "b",1,
[junit4] 2> "e",1,
[junit4] 2> "h",1,
[junit4] 2> "m",1,
[junit4] 2> "n",1,
[junit4] 2> "o",1,
[junit4] 2> "s",1,
[junit4] 2> "t",1]},
[junit4] 2> "facet_ranges":{},
[junit4] 2> "facet_intervals":{},
[junit4] 2> "facet_heatmaps":{}}}
[junit4] 2>
[junit4] 2> request = facet=true&wt=json&indent=true&omitHeader=true&q=*:*&rows=0&facet.offset=0&facet.sort=count&facet.field=small4_ss&facet.method=uif
[junit4] 2> 1056918 INFO (TEST-TestRandomDVFaceting.testRandomFaceting-seed#[DCB4BC7ED3EC8D76]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomFaceting
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomDVFaceting -Dtests.method=testRandomFaceting -Dtests.seed=DCB4BC7ED3EC8D76 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=hr -Dtests.timezone=Asia/Pontianak -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 4.21s J2 | TestRandomDVFaceting.testRandomFaceting <<<
[junit4] > Throwable #1: java.lang.AssertionError: mismatch: 'k'!='j' @ facet_counts/facet_fields/small4_ss/[6]
[junit4] > at __randomizedtesting.SeedInfo.seed([DCB4BC7ED3EC8D76:D1DC9CAB861545C9]:0)
[junit4] > at org.apache.solr.TestRandomDVFaceting.doFacetTests(TestRandomDVFaceting.java:292)
[junit4] > at org.apache.solr.TestRandomDVFaceting.doFacetTests(TestRandomDVFaceting.java:175)
[junit4] > at org.apache.solr.TestRandomDVFaceting.testRandomFaceting(TestRandomDVFaceting.java:158)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1056919 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1056919 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=675154319
[junit4] 2> 1056919 INFO (coreCloseExecutor-8676-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2685f501
[junit4] 2> 1056920 INFO (coreCloseExecutor-8676-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@2685f501
[junit4] 2> 1056920 INFO (coreCloseExecutor-8676-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5fb19462: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@215b126e
[junit4] 2> 1056932 INFO (coreCloseExecutor-8676-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1056933 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1056933 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c7a8c08: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@40a35374
[junit4] 2> 1056938 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1056938 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4163232: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@289f3a02
[junit4] 2> 1056940 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1056940 INFO (SUITE-TestRandomDVFaceting-seed#[DCB4BC7ED3EC8D76]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@28765941: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@59a19667
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.TestRandomDVFaceting_DCB4BC7ED3EC8D76-001
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=22859, maxDocsPerChunk=2, blockShift=6), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=22859, blockSize=6)), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=hr, timezone=Asia/Pontianak
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=97531160,total=532676608
[junit4] 2> NOTE: All tests run in this JVM: [CurrencyRangeFacetCloudTest, ExecutePlanActionTest, TestSolrJacksonAnnotation, TestPhraseSuggestions, PeerSyncWithLeaderAndIndexFingerprintCachingTest, ConvertedLegacyTest, TestSimLargeCluster, BasicDistributedZk2Test, SSLMigrationTest, TestFileDictionaryLookup, TestDeleteCollectionOnDownNodes, TestScoreJoinQPScore, SimpleMLTQParserTest, AtomicUpdateRemovalJavabinTest, PreAnalyzedFieldManagedSchemaCloudTest, TestSimpleTextCodec, CircularListTest, TestCloudRecovery2, TestRetrieveFieldsOptimizer, SpatialRPTFieldTypeTest, DirectSolrSpellCheckerTest, ForceLeaderTest, BlobRepositoryCloudTest, HighlighterTest, TestWaitForStateWithJettyShutdowns, TestDFISimilarityFactory, TestCharFilters, HdfsRestartWhileUpdatingTest, DistributedQueryElevationComponentTest, MaxSizeAutoCommitTest, TestXmlQParserPlugin, CollectionTooManyReplicasTest, AutoAddReplicasIntegrationTest, TestRealTimeGet, SharedFSAutoReplicaFailoverTest, CheckHdfsIndexTest, TestDynamicURP, FacetPivotSmallTest, TestEmbeddedSolrServerConstructors, TestManagedResource, BaseCdcrDistributedZkTest, TestDistributedStatsComponentCardinality, NodeMutatorTest, TestPrepRecovery, TestJsonFacetRefinement, ActionThrottleTest, MetricUtilsTest, AuditLoggerIntegrationTest, RemoteQueryErrorTest, TestCorePropertiesReload, PrimUtilsTest, DebugComponentTest, QueryElevationComponentTest, DynamicMapsTest, UpdateParamsTest, BigEndianAscendingWordSerializerTest, TestObjectReleaseTracker, TestInPlaceUpdatesDistrib, TestSolrCloudSnapshots, ZkControllerTest, TestDocumentBuilder, TestLMJelinekMercerSimilarityFactory, ExitableDirectoryReaderTest, JWTVerificationkeyResolverTest, TestCircuitBreaker, TestFieldResource, ConcurrentDeleteAndCreateCollectionTest, TriggerSetPropertiesIntegrationTest, DocValuesMissingTest, TestFaceting, DimensionalRoutedAliasUpdateProcessorTest, TestStressCloudBlindAtomicUpdates, CdcrUpdateLogTest, TestSchemaResource, EchoParamsTest, RankQParserPluginTest, ConcurrentCreateRoutedAliasTest, TestWordDelimiterFilterFactory, ClassificationUpdateProcessorIntegrationTest, DistribJoinFromCollectionTest, ResponseHeaderTest, TestCoreBackup, TestUnifiedSolrHighlighterWithoutStoredId, HdfsChaosMonkeySafeLeaderTest, SolrIndexSearcherTest, RAMDirectoryFactoryTest, CopyFieldTest, TestFieldCache, NodeAddedTriggerIntegrationTest, TestSchemalessBufferedUpdates, TestLegacyNumericRangeQueryBuilder, JvmMetricsTest, SimpleCollectionCreateDeleteTest, TestPHPSerializedResponseWriter, UniqFieldsUpdateProcessorFactoryTest, ParsingFieldUpdateProcessorsTest, SuggestComponentTest, GraphQueryTest, TestConfigSetProperties, TestQuerySenderListener, TestTermsQParserPlugin, DaemonStreamApiTest, SolrXmlInZkTest, CoreAdminHandlerTest, TimeZoneUtilsTest, TestFieldCacheVsDocValues, TestReloadDeadlock, TestTrackingShardHandlerFactory, BadComponentTest, SolrShardReporterTest, HdfsUnloadDistributedZkTest, TestUseDocValuesAsStored2, TestCloudPhrasesIdentificationComponent, HdfsDirectoryTest, TestSkipOverseerOperations, AnalysisAfterCoreReloadTest, DisMaxRequestHandlerTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4DeleteCoreTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestHighlightDedupGrouping, TestJoin, TestRandomDVFaceting]
[junit4] Completed [399/921 (1!)] on J2 in 4.59s, 1 test, 1 failure <<< FAILURES!
[...truncated 52383 lines...]