Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/3177/

2 tests failed.
FAILED:  org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([12832E53C1E2BF64:FA5F83B1320AA544]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1024)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:984)
        at 
org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:245)
        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)
Caused by: org.apache.solr.common.SolrException: Circuit Breakers tripped 
Memory Circuit Breaker triggered as JVM heap usage values are greater than 
allocated threshold.Seen JVM heap memory usage 441914336 and allocated 
threshold 397934592

        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:321)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
        at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
        at org.apache.solr.util.TestHarness.query(TestHarness.java:328)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:998)
        ... 45 more


FAILED:  org.apache.solr.search.TestFiltering.testRandomFiltering

Error Message:
should have unwrapped

Stack Trace:
java.lang.AssertionError: should have unwrapped
        at 
__randomizedtesting.SeedInfo.seed([12832E53C1E2BF64:DE08D9624C5FF6D]:0)
        at 
org.apache.solr.search.SolrIndexSearcher.getAndCacheDocSet(SolrIndexSearcher.java:862)
        at 
org.apache.solr.search.SolrIndexSearcher.getDocSet(SolrIndexSearcher.java:824)
        at 
org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1367)
        at 
org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:596)
        at 
org.apache.solr.handler.component.QueryComponent.doProcessUngroupedSearch(QueryComponent.java:1511)
        at 
org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:390)
        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:368)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
        at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
        at org.apache.solr.util.TestHarness.query(TestHarness.java:328)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:1090)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:1059)
        at 
org.apache.solr.search.TestFiltering.testRandomFiltering(TestFiltering.java:488)
        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 14151 lines...]
   [junit4] Suite: org.apache.solr.search.TestFiltering
   [junit4]   2> 619720 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-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> 619720 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-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.search.TestFiltering_12832E53C1E2BF64-001/data-dir-39-001
   [junit4]   2> 619720 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
   [junit4]   2> 619720 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 619722 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 619722 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 619722 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 619728 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 619742 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 619742 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.a.s.c.SolrConfig 
solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no 
effect.
   [junit4]   2> 619742 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.a.s.c.SolrConfig 
solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter 
section instead
   [junit4]   2> 619745 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.s.IndexSchema Schema name=example
   [junit4]   2> 619747 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 620076 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-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_291]
   [junit4]   2>        at 
java.security.AccessController.checkPermission(AccessController.java:886) 
~[?:1.8.0_291]
   [junit4]   2>        at 
java.lang.SecurityManager.checkPermission(SecurityManager.java:549) 
~[?:1.8.0_291]
   [junit4]   2>        at 
java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_291]
   [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_291]
   [junit4]   2>        at 
java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_291]
   [junit4]   2>        at 
java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_291]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:378) [java/:?]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:343) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:183) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:813) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:803) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:664) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:653) [java/:?]
   [junit4]   2>        at 
org.apache.solr.search.TestFiltering.beforeTests(TestFiltering.java:45) 
[test/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_291]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_291]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_291]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_291]
   [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_291]
   [junit4]   2> 620077 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-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_291]
   [junit4]   2>        at 
java.security.AccessController.checkPermission(AccessController.java:886) 
~[?:1.8.0_291]
   [junit4]   2>        at 
java.lang.SecurityManager.checkPermission(SecurityManager.java:549) 
~[?:1.8.0_291]
   [junit4]   2>        at 
java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_291]
   [junit4]   2>        at java.io.File.mkdir(File.java:1348) ~[?:1.8.0_291]
   [junit4]   2>        at java.io.File.mkdirs(File.java:1380) ~[?:1.8.0_291]
   [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:679) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:184) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:813) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:803) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:664) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:653) [java/:?]
   [junit4]   2>        at 
org.apache.solr.search.TestFiltering.beforeTests(TestFiltering.java:45) 
[test/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_291]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_291]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_291]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_291]
   [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_291]
   [junit4]   2> 620078 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 620082 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.e.j.u.s.S.config 
Trusting all certificates configured for 
Client@442ef452[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 620082 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.e.j.u.s.S.config 
No Client EndPointIdentificationAlgorithm configured for 
Client@442ef452[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 620084 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.e.j.u.s.S.config 
Trusting all certificates configured for 
Client@408b79b7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 620084 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] o.e.j.u.s.S.config 
No Client EndPointIdentificationAlgorithm configured for 
Client@408b79b7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 620086 WARN  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-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> 620111 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd7d1d7
   [junit4]   2> 620117 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd7d1d7
   [junit4]   2> 620117 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd7d1d7
   [junit4]   2> 620119 INFO  (coreLoadExecutor-4078-thread-1) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 
2147483647 cores with initial capacity of 1024
   [junit4]   2> 620127 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from 
paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 620145 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 620145 WARN  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is 
currently not implemented and has no effect.
   [junit4]   2> 620145 WARN  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer 
supported, use solr.xml:/metrics/reporter section instead
   [junit4]   2> 620148 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=example
   [junit4]   2> 620150 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 620157 INFO  (coreLoadExecutor-4078-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> 620157 INFO  (coreLoadExecutor-4078-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@fd7d1d7
   [junit4]   2> 620157 INFO  (coreLoadExecutor-4078-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/J1/temp/solr.search.TestFiltering_12832E53C1E2BF64-001/data-dir-39-001/]
   [junit4]   2> 620158 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.17571666799296937]
   [junit4]   2> 620217 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 620217 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 620218 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 620219 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 620233 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 620307 INFO  (coreLoadExecutor-4078-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 620308 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 620309 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 620309 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 620310 INFO  
(SUITE-TestFiltering-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 620310 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620314 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testCaching
   [junit4]   2> 620314 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 620314 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
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> 620315 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 620317 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 620317 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 620318 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 620329 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620330 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 11
   [junit4]   2> 620331 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D3+cache%3Dfalse}val_i&wt=xml} hits=2 
status=0 QTime=0
   [junit4]   2> 620332 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D3+cache%3Dfalse}val_i&wt=xml} hits=2 
status=0 QTime=0
   [junit4]   2> 620333 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D4}val_i&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 620333 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D4+cost%3D0}val_i&wt=xml} hits=3 status=0 
QTime=0
   [junit4]   2> 620333 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D4+cost%3D999}val_i&wt=xml} hits=3 status=0 
QTime=0
   [junit4]   2> 620333 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D5+cache%3Dfalse+cost%3D0}val_i&wt=xml} 
hits=3 status=0 QTime=0
   [junit4]   2> 620334 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D6+cache%3Dfalse}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0
   [junit4]   2> 620335 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D6+cache%3Dfalse}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0
   [junit4]   2> 620335 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D7}val_i&facet=true&wt=xml} 
hits=3 status=0 QTime=0
   [junit4]   2> 620336 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D7+cost%3D0}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0
   [junit4]   2> 620336 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D7+cost%3D999}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0
   [junit4]   2> 620336 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D8+cache%3Dfalse+cost%3D0}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0
   [junit4]   2> 620337 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dfalse}*:*&fl=val_i&start=2&sort=val_i+asc&rows=1&wt=xml} 
hits=4 status=0 QTime=0
   [junit4]   2> 620338 INFO  
(TEST-TestFiltering.testCaching-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testCaching
   [junit4]   2> 620343 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testHossssSanity
   [junit4]   2> 620343 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 620343 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
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> 620344 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[0]} 0 0
   [junit4]   2> 620344 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 620344 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 620344 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 620345 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 620345 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 620345 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 620345 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 620346 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 620346 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 620352 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620356 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 9
   [junit4]   2> 620356 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=10 status=0 QTime=0
   [junit4]   2> 620357 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D0+u%3D1}&fq={!frange+v%3Dval_i+l%3D0+u%3D1}&fq={!frange+v%3Dval_i+l%3D0+u%3D1}&fq=-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D1}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D1}"}
 hits=1 status=0 QTime=0
   [junit4]   2> 620357 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D0+u%3D1}&fq={!frange+v%3Dval_i+l%3D1+u%3D1}&fq={!frange+v%3Dval_i+l%3D0+u%3D1}&fq=-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D1}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D0+u%3D1}"}
 hits=0 status=0 QTime=0
   [junit4]   2> 620357 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 0
   [junit4]   2> 620358 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D0+u%3D1}&fq={!frange+v%3Dval_i+l%3D1+u%3D1}&fq={!frange+v%3Dval_i+l%3D0+u%3D1}&fq=-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D1}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D0+u%3D1}"}
 hits=0 status=0 QTime=0
   [junit4]   2> 620358 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D0+u%3D1}&fq={!frange+v%3Dval_i+l%3D0+u%3D1}&fq={!frange+v%3Dval_i+l%3D0+u%3D1}&fq=-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D1}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D1}"}
 hits=1 status=0 QTime=0
   [junit4]   2> 620359 INFO  
(TEST-TestFiltering.testHossssSanity-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testHossssSanity
   [junit4]   2> 620363 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testLiveDocsSharing
   [junit4]   2> 620363 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 620363 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [     ] 
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> 620363 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[0]} 0 0
   [junit4]   2> 620364 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[0]} 0 0
   [junit4]   2> 620364 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 620364 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 620365 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 620365 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 620365 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 620365 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 620365 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 620366 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[7]} 0 0
   [junit4]   2> 620366 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[8]} 0 0
   [junit4]   2> 620366 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[9]} 0 0
   [junit4]   2> 620366 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 620366 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[10]} 0 0
   [junit4]   2> 620367 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[11]} 0 0
   [junit4]   2> 620367 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[12]} 0 0
   [junit4]   2> 620367 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[13]} 0 0
   [junit4]   2> 620367 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[14]} 0 0
   [junit4]   2> 620368 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 620368 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[15]} 0 0
   [junit4]   2> 620368 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[16]} 0 0
   [junit4]   2> 620368 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[17]} 0 0
   [junit4]   2> 620368 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[18]} 0 0
   [junit4]   2> 620369 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[19]} 0 0
   [junit4]   2> 620375 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620376 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 6
   [junit4]   2> 620382 INFO  
(TEST-TestFiltering.testLiveDocsSharing-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testLiveDocsSharing
   [junit4]   2> 620385 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testRandomFiltering
   [junit4]   2> 620386 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 620386 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
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> 620386 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[0]} 0 0
   [junit4]   2> 620392 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620395 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 8
   [junit4]   2> 620396 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[1]} 0 0
   [junit4]   2> 620396 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[1]} 0 0
   [junit4]   2> 620396 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[2]} 0 0
   [junit4]   2> 620396 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[2]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[3]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[4]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[5]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[6]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[7]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[7]} 0 0
   [junit4]   2> 620397 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[8]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[9]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[10]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[11]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[12]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[13]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[14]} 0 0
   [junit4]   2> 620398 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[15]} 0 0
   [junit4]   2> 620399 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[16]} 0 0
   [junit4]   2> 620399 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[17]} 0 0
   [junit4]   2> 620399 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[18]} 0 0
   [junit4]   2> 620399 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[19]} 0 0
   [junit4]   2> 620399 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[20]} 0 0
   [junit4]   2> 620399 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[21]} 0 0
   [junit4]   2> 620400 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[22]} 0 0
   [junit4]   2> 620400 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[23]} 0 0
   [junit4]   2> 620400 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[24]} 0 0
   [junit4]   2> 620400 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[25]} 0 0
   [junit4]   2> 620407 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620407 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 7
   [junit4]   2> 620408 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[26]} 0 0
   [junit4]   2> 620408 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[27]} 0 0
   [junit4]   2> 620415 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620415 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 7
   [junit4]   2> 620416 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[28]} 0 0
   [junit4]   2> 620416 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[29]} 0 0
   [junit4]   2> 620416 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[30]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[31]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[32]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[32]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[33]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[34]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[35]} 0 0
   [junit4]   2> 620417 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[35]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[36]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[37]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[38]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[38]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[39]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[40]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[41]} 0 0
   [junit4]   2> 620418 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[42]} 0 0
   [junit4]   2> 620419 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[42]} 0 0
   [junit4]   2> 620419 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[43]} 0 0
   [junit4]   2> 620419 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[43]} 0 0
   [junit4]   2> 620419 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[44]} 0 0
   [junit4]   2> 620419 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[45]} 0 0
   [junit4]   2> 620425 INFO  
(searcherExecutor-4080-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 620426 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 6
   [junit4]   2> 620426 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=46 status=0 QTime=0
   [junit4]   2> 620431 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:7+-val_i:8+-val_i:9+-val_i:12+-val_i:15+-val_i:16+-val_i:17+-val_i:19+-val_i:21+-val_i:24+-val_i:26+-val_i:28+-val_i:29+-val_i:32+-val_i:33+-val_i:35+-val_i:41+-val_i:43+-val_i:44&q={!+tag%3Dt}-_query_:"val_s:[00032+TO+00046}"&fq=+val_i:0+val_i:1+val_i:4+val_i:5+val_i:6+val_i:7+val_i:11+val_i:12+val_i:13+val_i:14+val_i:15+val_i:16+val_i:21+val_i:24+val_i:25+val_i:26+val_i:28+val_i:30+val_i:32+val_i:35+val_i:36+val_i:37+val_i:38+val_i:39+val_i:40+val_i:43+val_i:44+val_i:45&facet=true&wt=xml}
 hits=18 status=0 QTime=3
   [junit4]   2> 620433 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cache%3Dfalse+cost%3D20}-_query_:"val_s:{00019+TO+00046}"&fq={!cache%3Dfalse+cost%3D138}+val_i:0+val_i:2+val_i:4+val_i:13+val_i:17+val_i:18+val_i:19+val_i:20+val_i:32+val_i:34+val_i:43+val_i:45&fq={!cache%3Dfalse}+-val_i:5+-val_i:6+-val_i:10+-val_i:12+-val_i:13+-val_i:17+-val_i:20+-val_i:21+-val_i:24+-val_i:26+-val_i:27+-val_i:29+-val_i:31+-val_i:33+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:40+-val_i:42+-val_i:44&fq={!frange+v%3Dval_i+l%3D11+u%3D55}&wt=xml}
 hits=2 status=0 QTime=1
   [junit4]   2> 620434 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D133}-_query_:"{!frange+v%3Dval_i+l%3D26+u%3D45}"&q={!+cache%3Dtrue+tag%3Dt}val_i:{00037+TO+00083}+OR+id:RAND-1347660709&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:5+val_i:6+val_i:7+val_i:8+val_i:9+val_i:10+val_i:11+val_i:12+val_i:14+val_i:15+val_i:17+val_i:18+val_i:23+val_i:24+val_i:27+val_i:29+val_i:31+val_i:32+val_i:33+val_i:34+val_i:35+val_i:37+val_i:41+val_i:44+val_i:45&fq=val_i:[00045+TO+00045]+AND+val_i:{00044+TO+00046}&fq={!frange+v%3Dval_i+l%3D36+u%3D62}&facet=true&wt=xml}
 hits=1 status=0 QTime=1
   [junit4]   2> 620436 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D55}val_s:[00020+TO+00045]&q={!cache%3Dtrue}+-val_i:0+-val_i:4+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:18+-val_i:22+-val_i:23+-val_i:24+-val_i:27+-val_i:29+-val_i:30+-val_i:33+-val_i:35+-val_i:38+-val_i:39&fq={!+tag%3Dt}val_s:{00040+TO+00077}+OR+id:RAND-1158464068&facet=true&wt=xml}
 hits=5 status=0 QTime=1
   [junit4]   2> 620438 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}+-val_i:0+-val_i:1+-val_i:9+-val_i:10+-val_i:12+-val_i:14+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:28+-val_i:30+-val_i:31+-val_i:32+-val_i:35+-val_i:38+-val_i:39+-val_i:40+-val_i:45&q={!cache%3Dfalse}+val_i:2+val_i:3+val_i:4+val_i:8+val_i:9+val_i:13+val_i:14+val_i:19+val_i:22+val_i:23+val_i:26+val_i:28+val_i:32+val_i:34+val_i:36+val_i:37+val_i:39+val_i:41&fq={!+cost%3D109}-_query_:"val_i:{00032+TO+00045]"&facet=true&wt=xml}
 hits=13 status=0 QTime=1
   [junit4]   2> 620442 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}+val_i:0+val_i:4+val_i:5+val_i:10+val_i:11+val_i:13+val_i:14+val_i:15+val_i:16+val_i:17+val_i:18+val_i:19+val_i:20+val_i:21+val_i:22+val_i:27+val_i:28+val_i:29+val_i:30+val_i:32+val_i:34+val_i:38+val_i:39+val_i:40+val_i:42+val_i:43+val_i:44+val_i:45&q={!cache%3Dfalse}+-val_i:1+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:14+-val_i:17+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:32+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:41+-val_i:43+-val_i:45&fq={!frange+v%3Dval_i+l%3D21+u%3D65+cache%3Dfalse+cost%3D66+tag%3Dt}&fq=val_s:[00021+TO+00051]+AND+val_s:{00020+TO+00052}&fq={!frange+v%3Dval_i+l%3D40+u%3D53+cache%3Dfalse+cost%3D107+tag%3Dt}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D20+u%3D45}"&facet=true&wt=xml}
 hits=0 status=0 QTime=4
   [junit4]   2> 620469 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D13+u%3D53+cost%3D70}&group.main=true&group.cache.percent=100&wt=xml&group.field=id&group=true}
 hits=33 status=0 QTime=26
   [junit4]   2> 620471 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+val_i:1+val_i:2+val_i:3+val_i:4+val_i:6+val_i:7+val_i:10+val_i:16+val_i:17+val_i:18+val_i:20+val_i:22+val_i:23+val_i:24+val_i:26+val_i:28+val_i:29+val_i:30+val_i:36+val_i:38+val_i:39+val_i:40+val_i:43&q=+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:5+-val_i:6+-val_i:15+-val_i:19+-val_i:21+-val_i:23+-val_i:24+-val_i:27+-val_i:28+-val_i:31+-val_i:34+-val_i:35+-val_i:38+-val_i:42+-val_i:43+-val_i:44+-val_i:45&facet=true&wt=xml}
 hits=25 status=0 QTime=1
   [junit4]   2> 620475 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00013+TO+00045]"&group.main=true&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:4+-val_i:12+-val_i:13+-val_i:14+-val_i:16+-val_i:17+-val_i:18+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:28+-val_i:29+-val_i:31+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:40+-val_i:41+-val_i:42+-val_i:44&fq={!cache%3Dfalse}+val_i:1+val_i:3+val_i:4+val_i:5+val_i:7+val_i:8+val_i:10+val_i:12+val_i:18+val_i:19+val_i:21+val_i:23+val_i:24+val_i:25+val_i:26+val_i:27+val_i:28+val_i:29+val_i:33+val_i:34+val_i:35+val_i:36+val_i:37+val_i:38+val_i:42+val_i:43+val_i:44&fq={!cache%3Dtrue+tag%3Dt}+val_i:4+val_i:5+val_i:6+val_i:7+val_i:10+val_i:11+val_i:14+val_i:16+val_i:17+val_i:19+val_i:26+val_i:28+val_i:29+val_i:33+val_i:34+val_i:35+val_i:37+val_i:39+val_i:40+val_i:41+val_i:45&wt=xml&group.field=id&group=true}
 hits=3 status=0 QTime=2
   [junit4]   2> 620476 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cache%3Dfalse+cost%3D128}-_query_:"{!frange+v%3Dval_i+l%3D10+u%3D45}"&group.main=true&fq={!cache%3Dfalse+tag%3Dt}+val_i:2+val_i:4+val_i:6+val_i:7+val_i:10+val_i:11+val_i:16+val_i:17+val_i:18+val_i:19+val_i:20+val_i:21+val_i:22+val_i:25+val_i:26+val_i:31+val_i:33+val_i:38+val_i:39+val_i:40+val_i:42+val_i:44+val_i:45&fq={!+cache%3Dfalse}-_query_:"val_i:{00027+TO+00046}"&wt=xml&group.field=id&group=true}
 hits=4 status=0 QTime=0
   [junit4]   2> 620478 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}-_query_:"val_s:{00006+TO+00045]"&q=+-val_i:3+-val_i:4+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:15+-val_i:17+-val_i:18+-val_i:21+-val_i:22+-val_i:24+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:32+-val_i:36+-val_i:37+-val_i:38+-val_i:40+-val_i:44+-val_i:45&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:5+val_i:7+val_i:9+val_i:11+val_i:12+val_i:14+val_i:15+val_i:17+val_i:18+val_i:20+val_i:24+val_i:26+val_i:27+val_i:29+val_i:33+val_i:42+val_i:44&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00010+TO+00046}"&facet=true&wt=xml}
 hits=5 status=0 QTime=2
   [junit4]   2> 620480 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cache%3Dtrue+tag%3Dt}val_i:[00032+TO+00064]+OR+id:RAND-223369400&fq={!cache%3Dtrue}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:7+-val_i:8+-val_i:10+-val_i:15+-val_i:17+-val_i:18+-val_i:22+-val_i:24+-val_i:29+-val_i:30+-val_i:31+-val_i:33+-val_i:39+-val_i:40+-val_i:43+-val_i:44+-val_i:45&fq={!cache%3Dtrue}+-val_i:2+-val_i:4+-val_i:5+-val_i:7+-val_i:10+-val_i:11+-val_i:15+-val_i:16+-val_i:18+-val_i:19+-val_i:22+-val_i:24+-val_i:26+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:42&wt=xml}
 hits=2 status=0 QTime=1
   [junit4]   2> 620482 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}-_query_:"{!frange+v%3Dval_i+l%3D42+u%3D45}"&q={!cache%3Dfalse}+val_i:1+val_i:2+val_i:3+val_i:6+val_i:8+val_i:9+val_i:12+val_i:15+val_i:17+val_i:18+val_i:20+val_i:21+val_i:23+val_i:26+val_i:28+val_i:29+val_i:31+val_i:32+val_i:34+val_i:37+val_i:41+val_i:43&fq={!+cache%3Dfalse+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D31+u%3D45}"&facet=true&wt=xml}
 hits=16 status=0 QTime=1
   [junit4]   2> 620483 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=val_i:{00019+TO+00065}&fq={!cache%3Dfalse+cost%3D56}+-val_i:0+-val_i:1+-val_i:4+-val_i:7+-val_i:8+-val_i:11+-val_i:12+-val_i:15+-val_i:16+-val_i:18+-val_i:19+-val_i:20+-val_i:22+-val_i:23+-val_i:25+-val_i:27+-val_i:30+-val_i:35+-val_i:39&fq={!cache%3Dfalse+tag%3Dt}+-val_i:0+-val_i:4+-val_i:11+-val_i:13+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:21+-val_i:22+-val_i:28+-val_i:33+-val_i:37+-val_i:39+-val_i:40+-val_i:42+-val_i:45&wt=xml}
 hits=11 status=0 QTime=1
   [junit4]   2> 620485 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:3+val_i:4+val_i:5+val_i:6+val_i:7+val_i:8+val_i:9+val_i:11+val_i:13+val_i:14+val_i:15+val_i:16+val_i:18+val_i:19+val_i:20+val_i:22+val_i:24+val_i:26+val_i:27+val_i:28+val_i:30+val_i:31+val_i:33+val_i:37+val_i:39+val_i:40+val_i:42+val_i:43&fq={!+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D0+u%3D45}"&fq={!+cache%3Dfalse}-_query_:"{!frange+v%3Dval_i+l%3D14+u%3D45}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D27+u%3D45}"&fq={!frange+v%3Dval_i+l%3D32+u%3D77+cost%3D182+tag%3Dt}&wt=xml}
 hits=0 status=0 QTime=1
   [junit4]   2> 620487 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dtrue}+val_i:0+val_i:3+val_i:5+val_i:6+val_i:8+val_i:11+val_i:12+val_i:13+val_i:14+val_i:16+val_i:18+val_i:25+val_i:30+val_i:32+val_i:34+val_i:35+val_i:36+val_i:37+val_i:39+val_i:40+val_i:43&q={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D15+u%3D45}"&fq={!cache%3Dfalse}+-val_i:1+-val_i:2+-val_i:3+-val_i:5+-val_i:7+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:18+-val_i:21+-val_i:22+-val_i:23+-val_i:26+-val_i:28+-val_i:31+-val_i:33+-val_i:35+-val_i:36+-val_i:39+-val_i:40+-val_i:41+-val_i:42+-val_i:45&fq={!+cache%3Dfalse}val_s:[00042+TO+00069]+OR+val_s:{00041+TO+00070}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:{00029+TO+00046}"&fq={!cache%3Dfalse+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:4+val_i:8+val_i:11+val_i:13+val_i:14+val_i:18+val_i:19+val_i:20+val_i:21+val_i:23+val_i:24+val_i:28+val_i:32+val_i:33+val_i:34+val_i:35+val_i:37+val_i:38+val_i:39+val_i:40+val_i:42+val_i:43+val_i:44&facet=true&wt=xml}
 hits=0 status=0 QTime=2
   [junit4]   2> 620488 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cache%3Dfalse+cost%3D155+tag%3Dt}-_query_:"val_s:[00038+TO+00046}"&fq={!+cache%3Dfalse+cost%3D87}-_query_:"val_s:{00017+TO+00045]"&fq={!frange+v%3Dval_i+l%3D14+u%3D45+cache%3Dfalse+cost%3D2}&wt=xml}
 hits=4 status=0 QTime=0
   [junit4]   2> 620489 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dfalse+tag%3Dt}+val_i:5+val_i:7+val_i:8+val_i:10+val_i:11+val_i:12+val_i:13+val_i:16+val_i:17+val_i:19+val_i:20+val_i:22+val_i:24+val_i:26+val_i:28+val_i:30+val_i:31+val_i:32+val_i:34+val_i:35+val_i:36+val_i:37+val_i:39+val_i:43+val_i:45&fq={!+cost%3D61+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D5+u%3D45}"&wt=xml}
 hits=0 status=0 QTime=0
   [junit4]   2> 620490 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D31+u%3D42+tag%3Dt}&fq={!+cost%3D104+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D16+u%3D45}"&fq={!frange+v%3Dval_i+l%3D35+u%3D59+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dfalse+tag%3Dt}+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:13+-val_i:14+-val_i:15+-val_i:19+-val_i:20+-val_i:21+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:39+-val_i:40+-val_i:43+-val_i:45&fq=val_i:[00025+TO+00059}&wt=xml}
 hits=0 status=0 QTime=1
   [junit4]   2> 620501 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse+cost%3D185}+-val_i:3+-val_i:9+-val_i:10+-val_i:14+-val_i:15+-val_i:16+-val_i:19+-val_i:21+-val_i:22+-val_i:24+-val_i:27+-val_i:30+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:39+-val_i:41+-val_i:43+-val_i:45&q={!cache%3Dfalse}+-val_i:0+-val_i:2+-val_i:4+-val_i:5+-val_i:6+-val_i:9+-val_i:11+-val_i:12+-val_i:15+-val_i:17+-val_i:18+-val_i:21+-val_i:22+-val_i:24+-val_i:25+-val_i:26+-val_i:31+-val_i:33+-val_i:37+-val_i:38+-val_i:39+-val_i:41&fq={!cache%3Dtrue}+val_i:2+val_i:3+val_i:6+val_i:11+val_i:17+val_i:18+val_i:19+val_i:21+val_i:22+val_i:25+val_i:26+val_i:28+val_i:29+val_i:30+val_i:32+val_i:33+val_i:35+val_i:36+val_i:38+val_i:39+val_i:40+val_i:41+val_i:42&facet=true&wt=xml}
 hits=10 status=0 QTime=10
   [junit4]   2> 620502 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cache%3Dfalse+tag%3Dt}val_i:[00017+TO+00031]&wt=xml} hits=15 
status=0 QTime=0
   [junit4]   2> 620503 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cache%3Dfalse}-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D45}"&q=-_query_:"{!frange+v%3Dval_i+l%3D35+u%3D45}"&fq={!cache%3Dfalse+tag%3Dt}+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:9+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:20+-val_i:23+-val_i:24+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:37+-val_i:39+-val_i:40+-val_i:41&facet=true&wt=xml}
 hits=13 status=0 QTime=1
   [junit4]   2> 620505 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:5+-val_i:7+-val_i:8+-val_i:11+-val_i:12+-val_i:14+-val_i:15+-val_i:16+-val_i:19+-val_i:20+-val_i:23+-val_i:25+-val_i:28+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:45&fq={!cache%3Dfalse}+-val_i:2+-val_i:3+-val_i:4+-val_i:6+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:18+-val_i:19+-val_i:20+-val_i:23+-val_i:26+-val_i:31+-val_i:32+-val_i:35+-val_i:43&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:[00032+TO+00046}"&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00030+TO+00051]&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D2+u%3D45}"&wt=xml}
 hits=0 status=0 QTime=1
   [junit4]   2> 620506 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:5+-val_i:6+-val_i:7+-val_i:10+-val_i:12+-val_i:13+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:28+-val_i:29+-val_i:33+-val_i:34+-val_i:37+-val_i:38+-val_i:39+-val_i:40+-val_i:41+-val_i:43+-val_i:45&wt=xml}
 hits=22 status=0 QTime=0
   [junit4]   2> 620507 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!+cost%3D160+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D12+u%3D45}"&fq={!+cache%3Dfalse+tag%3Dt}val_s:[00041+TO+00055}+OR+id:RAND-2134749696&fq=+-val_i:2+-val_i:4+-val_i:6+-val_i:9+-val_i:10+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:21+-val_i:23+-val_i:24+-val_i:28+-val_i:31+-val_i:37+-val_i:39+-val_i:41+-val_i:42+-val_i:43+-val_i:45&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00010+TO+00046}"&wt=xml}
 hits=0 status=0 QTime=1
   [junit4]   2> 620508 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+frange+v%3Dval_i+l%3D36+u%3D53+cache%3Dfalse+cost%3D54}&q={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:[00031+TO+00046}"&fq={!+cache%3Dfalse+cost%3D9+tag%3Dt}val_i:{00001+TO+00008]&fq=-_query_:"val_i:[00014+TO+00046}"&facet=true&wt=xml}
 hits=7 status=0 QTime=0
   [junit4]   2> 620511 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}+-val_i:0+-val_i:3+-val_i:4+-val_i:7+-val_i:8+-val_i:9+-val_i:11+-val_i:14+-val_i:15+-val_i:17+-val_i:18+-val_i:19+-val_i:25+-val_i:26+-val_i:28+-val_i:33+-val_i:36+-val_i:38+-val_i:39+-val_i:41+-val_i:42+-val_i:44&q={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:2+val_i:5+val_i:7+val_i:9+val_i:10+val_i:11+val_i:12+val_i:13+val_i:14+val_i:17+val_i:19+val_i:23+val_i:24+val_i:26+val_i:27+val_i:28+val_i:29+val_i:30+val_i:34+val_i:36+val_i:37+val_i:38+val_i:40+val_i:44&fq=-_query_:"{!frange+v%3Dval_i+l%3D32+u%3D45}"&fq=+-val_i:0+-val_i:1+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:9+-val_i:11+-val_i:12+-val_i:14+-val_i:15+-val_i:20+-val_i:21+-val_i:23+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:32+-val_i:35+-val_i:40+-val_i:42+-val_i:44&facet=true&wt=xml}
 hits=6 status=0 QTime=2
   [junit4]   2> 620513 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D177}-_query_:"{!frange+v%3Dval_i+l%3D39+u%3D45}"&q={!cache%3Dfalse}+-val_i:1+-val_i:3+-val_i:6+-val_i:7+-val_i:9+-val_i:10+-val_i:13+-val_i:14+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:22+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:33+-val_i:34+-val_i:37+-val_i:40+-val_i:41&fq={!frange+v%3Dval_i+l%3D30+u%3D54+cost%3D199+tag%3Dt}&fq={!+cache%3Dfalse+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D44+u%3D46}"&fq={!+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D4+u%3D45}"&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:1+val_i:7+val_i:8+val_i:13+val_i:15+val_i:16+val_i:17+val_i:19+val_i:21+val_i:22+val_i:25+val_i:27+val_i:28+val_i:29+val_i:36+val_i:37+val_i:39+val_i:43+val_i:44+val_i:45&facet=true&wt=xml}
 hits=0 status=0 QTime=1
   [junit4]   2> 620514 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+}*:*+AND+val_s:{00009+TO+00033}&q={!+tag%3Dt}-_query_:"val_s:{00016+TO+00046}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:4+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:21+-val_i:24+-val_i:25+-val_i:27+-val_i:28+-val_i:34+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:44+-val_i:45&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D10+u%3D45}"&facet=true&wt=xml}
 hits=7 status=0 QTime=1
   [junit4]   2> 620516 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dfalse}+-val_i:2+-val_i:5+-val_i:8+-val_i:10+-val_i:13+-val_i:14+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:33+-val_i:36+-val_i:37+-val_i:38+-val_i:40+-val_i:42+-val_i:44+-val_i:45&wt=xml}
 hits=21 status=0 QTime=0
   [junit4]   2> 620517 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+frange+v%3Dval_i+l%3D20+u%3D53}&q={!cache%3Dfalse+cost%3D43}+-val_i:0+-val_i:1+-val_i:2+-val_i:4+-val_i:6+-val_i:7+-val_i:10+-val_i:11+-val_i:12+-val_i:19+-val_i:20+-val_i:21+-val_i:23+-val_i:25+-val_i:26+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:37+-val_i:38+-val_i:42+-val_i:43&fq={!+cache%3Dfalse+cost%3D2+tag%3Dt}-_query_:"val_i:[00035+TO+00045]"&fq={!+cache%3Dfalse+cost%3D163+tag%3Dt}-_query_:"val_s:[00008+TO+00045]"&facet=true&wt=xml}
 hits=2 status=0 QTime=1
   [junit4]   2> 620518 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}-_query_:"val_i:[00041+TO+00046}"&q={!+tag%3Dt}val_s:[00030+TO+00051}+AND+val_s:{00029+TO+00050]&fq=-_query_:"val_i:{00020+TO+00045]"&facet=true&wt=xml}
 hits=0 status=0 QTime=0
   [junit4]   2> 620519 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+frange+v%3Dval_i+l%3D41+u%3D60}&q=id:RAND-1530653603+OR+val_s:[00030+TO+00076}&fq={!frange+v%3Dval_i+l%3D40+u%3D80+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml}
 hits=6 status=0 QTime=0
   [junit4]   2> 620521 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+frange+v%3Dval_i+l%3D32+u%3D55+cost%3D46}&q={!cache%3Dfalse}+val_i:0+val_i:2+val_i:5+val_i:9+val_i:10+val_i:13+val_i:14+val_i:16+val_i:18+val_i:19+val_i:22+val_i:23+val_i:27+val_i:28+val_i:30+val_i:31+val_i:33+val_i:38+val_i:42+val_i:43+val_i:45&group.main=true&group.cache.percent=100&fq={!cache%3Dfalse+cost%3D140+tag%3Dt}+val_i:2+val_i:3+val_i:5+val_i:7+val_i:10+val_i:11+val_i:13+val_i:15+val_i:16+val_i:19+val_i:21+val_i:24+val_i:25+val_i:26+val_i:28+val_i:29+val_i:30+val_i:31+val_i:36+val_i:37+val_i:42+val_i:43+val_i:44+val_i:45&fq={!+cache%3Dfalse}val_s:[00041+TO+00078}&facet=true&wt=xml&group.field=id&group=true}
 hits=3 status=0 QTime=1
   [junit4]   2> 620522 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D111+tag%3Dt}+-val_i:3+-val_i:5+-val_i:8+-val_i:13+-val_i:16+-val_i:18+-val_i:19+-val_i:22+-val_i:23+-val_i:30+-val_i:31+-val_i:34+-val_i:35+-val_i:39+-val_i:40+-val_i:43&fq={!frange+v%3Dval_i+l%3D13+u%3D28+tag%3Dt}&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 620523 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[12832E53C1E2BF64]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=+-val_i:2+-val_i:3+-val_i:6+-val_i:10+-val_i:11+-val_i:12+-val_i:14+-val_i:16+-val_i:18+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:26+-val_i:29+-val_i:31+-val_i:35+-val_i:37+-val_i:40+-val_i:41+-val_

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

stCaseJ4 ###Starting testBuildingMemoryPressure
   [junit4]   2> 2477514 INFO  (TestCircuitBreaker-22687-thread-1) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0
   [junit4]   2> 2477521 INFO  (TestCircuitBreaker-22687-thread-1) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0
   [junit4]   2> 2477529 INFO  (TestCircuitBreaker-22687-thread-1) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0
   [junit4]   2> 2477532 INFO  (TestCircuitBreaker-22687-thread-3) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
   [junit4]   2> 2477532 INFO  (TestCircuitBreaker-22687-thread-2) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0
   [junit4]   2> 2477537 INFO  
(TEST-TestCircuitBreaker.testBuildingMemoryPressure-seed#[12832E53C1E2BF64]) [  
   ] o.a.s.SolrTestCaseJ4 ###Ending testBuildingMemoryPressure
   [junit4]   2> 2477538 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2477538 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=755670724
   [junit4]   2> 2477547 INFO  (coreCloseExecutor-22689-thread-1) [     ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6bfdf8c8
   [junit4]   2> 2477548 INFO  (coreCloseExecutor-22689-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1 tag=SolrCore@6bfdf8c8
   [junit4]   2> 2477548 INFO  (coreCloseExecutor-22689-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@a3124f9: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1/com.codahale.metrics.MetricRegistry@484fdeea
   [junit4]   2> 2477567 INFO  (coreCloseExecutor-22689-thread-1) [     ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED 
(unnecessary).
   [junit4]   2> 2477598 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node 
tag=null
   [junit4]   2> 2477599 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@385f838d: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry 
solr.node/com.codahale.metrics.MetricRegistry@50a86344
   [junit4]   2> 2477604 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm 
tag=null
   [junit4]   2> 2477604 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7ff5689c: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry 
solr.jvm/com.codahale.metrics.MetricRegistry@1692cd5f
   [junit4]   2> 2477606 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 2477606 INFO  
(SUITE-TestCircuitBreaker-seed#[12832E53C1E2BF64]-worker) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@ca1dcb7: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty/com.codahale.metrics.MetricRegistry@28f1d556
   [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/J3/temp/solr.util.TestCircuitBreaker_12832E53C1E2BF64-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87), 
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-CU, 
timezone=Mexico/General
   [junit4]   2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 
1.8.0_291 (64-bit)/cpus=4,threads=1,free=67573656,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [TestFieldSortValues, 
HttpPartitionWithTlogReplicasTest, FieldAnalysisRequestHandlerTest, 
DistanceUnitsTest, StatsReloadRaceTest, TestCloudNestedDocsSort, 
SuggesterWFSTTest, TestSerializedLuceneMatchVersion, InfoHandlerTest, 
DistributedDebugComponentTest, ChangedSchemaMergeTest, 
HdfsBackupRepositoryIntegrationTest, ResponseHeaderTest, CursorPagingTest, 
TestConfigSetsAPIExclusivity, MetricTriggerTest, 
BigEndianAscendingWordDeserializerTest, TestSolrJ, 
TestPartialUpdateDeduplication, BasicDistributedZkTest, ShardSplitTest, 
ChaosMonkeySafeLeaderTest, SignificantTermsQParserPluginTest, 
TestManagedSynonymGraphFilterFactory, SOLR749Test, TestRandomFaceting, 
TestOverriddenPrefixQueryForCustomFieldType, 
DistributedSpellCheckComponentTest, EmbeddedSolrNoSerializeTest, ZkCLITest, 
HdfsChaosMonkeyNothingIsSafeTest, TestRawTransformer, HdfsDirectoryTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, 
ClassificationUpdateProcessorIntegrationTest, PolyFieldTest, 
OrderedExecutorTest, TestBulkSchemaAPI, TestStressThreadBackup, 
DimensionalRoutedAliasUpdateProcessorTest, TestValueSourceCache, 
ReplaceNodeNoTargetTest, TestFileDictionaryLookup, TestCollationFieldDocValues, 
TestBadConfig, SolrCloudReportersTest, MissingSegmentRecoveryTest, 
SolrXmlInZkTest, TestAddFieldRealTimeGet, ComputePlanActionTest, 
DirectoryFactoryTest, SortByFunctionTest, TestDocSet, TestLegacyField, 
AuditLoggerPluginTest, BlockJoinFacetRandomTest, TestRangeQuery, 
TestSubQueryTransformerDistrib, DistributedQueueTest, TestSearcherReuse, 
TestFieldCache, TestUtils, MetricsHandlerTest, TestPackages, 
TestSimDistribStateManager, ScriptEngineTest, TestSimGenericDistributedQueue, 
UUIDUpdateProcessorFallbackTest, RankFieldTest, 
SpellCheckCollatorWithCollapseTest, TestDistribPackageStore, 
TestSolrCLIRunExample, TestComplexPhraseLeadingWildcard, AnalyticsQueryTest, 
DocumentBuilderTest, WordBreakSolrSpellCheckerTest, CollectionPropsTest, 
TestTrie, TestLegacyBM25SimilarityFactory, 
HdfsTlogReplayBufferedWhileIndexingTest, JWTAuthPluginIntegrationTest, 
TestUseDocValuesAsStored, TestStressVersions, 
TlogReplayBufferedWhileIndexingTest, TestCloudPhrasesIdentificationComponent, 
TestRecoveryHdfs, SolrPluginUtilsTest, 
TestDistributedStatsComponentCardinality, TestChildDocTransformerHierarchy, 
LargeFieldTest, DeleteLastCustomShardedReplicaTest, TestDistributedGrouping, 
TestExactSharedStatsCache, TestSolr4Spatial2, TokenizerChainTest, 
DistributedFacetPivotLongTailTest, TestTrackingShardHandlerFactory, 
HighlighterMaxOffsetTest, SuggestComponentTest, TestRestoreCore, 
DateRangeFieldTest, TestPrepRecovery, TestInfoStreamLogging, 
SaslZkACLProviderTest, QueryElevationComponentTest, TestIndexingPerformance, 
TestClusterStateMutator, CdcrUpdateLogTest, CacheHeaderTest, 
TestFieldCollectionResource, TestSimPolicyCloud, RangeFacetCloudTest, 
CollectionsAPIDistributedZkTest, SystemLogListenerTest, TestGroupingSearch, 
TestQuerySenderNoQuery, CoreAdminCreateDiscoverTest, ShardTermsTest, 
SharedFSAutoReplicaFailoverTest, SplitShardTest, TestCloudPivotFacet, 
TestCloudRecovery2, TestHashPartitioner, TestOnReconnectListenerSupport, 
TestPullReplicaWithAuth, TestQueryingOnDownCollection, 
TestRandomRequestDistribution, TestSSLRandomization, 
TestShortCircuitedRequests, TestStressCloudBlindAtomicUpdates, 
TestWaitForStateWithJettyShutdowns, TestZkChroot, 
TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, 
BackupRestoreApiErrorConditionsTest, SimpleCollectionCreateDeleteTest, 
TestCollectionAPI, NodeAddedTriggerIntegrationTest, NodeLostTriggerTest, 
TestSimClusterStateProvider, TestSimExecutePlanAction, TestSimExtremeIndexing, 
TestSimLargeCluster, CdcrOpsAndBoundariesTest, HDFSCollectionsAPITest, 
ResourceLoaderTest, TestConfigOverlay, TestCoreContainer, 
TestSolrCoreSnapshots, TestHdfsBackupRestoreCore, TestIncrementalCoreBackup, 
XmlUpdateRequestHandlerTest, CoreAdminHandlerTest, IndexSizeEstimatorTest, 
SegmentsInfoRequestHandlerTest, ShowFileRequestHandlerTest, SplitHandlerTest, 
TestConfigsApi, ThreadDumpHandlerTest, ZookeeperStatusHandlerFailureTest, 
DistributedExpandComponentTest, DistributedFacetPivotLargeTest, 
DistributedQueryComponentOptimizationTest, DistributedTermsComponentTest, 
TermVectorComponentDistributedTest, TestHttpShardHandlerFactory, 
TestSchemaDesignerAPI, SolrSlf4jReporterTest, TestSchemaNameResource, 
TestManagedStopFilterFactory, TestOmitPositions, TestSchemaField, 
TestSchemaManager, UUIDFieldTest, AnalyticsMergeStrategyTest, 
QueryEqualityTest, QueryParsingTest, SortSpecParsingTest, TestCaffeineCache, 
TestInitQParser, TestLegacyNumericRangeQueryBuilder, TestMinHashQParser, 
TestNoOpRegenerator, TestRTGBase, TestRandomCollapseQParserPlugin, 
TestCloudJSONFacetSKGEquiv, TestJsonFacetsStatsParsing, 
TestJsonFacetsWithNestedObjects, AggValueSourceTest, TestSortByMinMaxFunction, 
TestNestedDocsSort, TestScoreJoinQPScore, BJQFilterAccessibleTest, 
CloudMLTQParserTest, TestSolrCloudWithHadoopAuthPlugin, 
TestFreeTextSuggestions, HdfsLockFactoryTest, TestDocTermOrds, 
TestDocTermOrdsUninvertLimit, TestFieldCacheSort, AddBlockUpdateTest, 
DeleteByIdWithRouterFieldTest, PeerSyncWithLeaderTest, 
TestInPlaceUpdateWithRouteField, TestInPlaceUpdatesDistrib, 
TimeRoutedAliasUpdateProcessorTest, TolerantUpdateProcessorTest, 
DynamicMapsTest, FileUtilsTest, NumberUtilsTest, PrimUtilsTest, 
SimplePostToolTest, TestCircuitBreaker]
   [junit4] Completed [927/947 (2!)] on J3 in 1.01s, 5 tests, 1 error <<< 
FAILURES!

[...truncated 54301 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscr...@lucene.apache.org
For additional commands, e-mail: builds-h...@lucene.apache.org

Reply via email to