You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/09/20 19:35:51 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 3212 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/3212/
1 tests failed.
FAILED: org.apache.solr.search.TestFiltering.testRandomFiltering
Error Message:
should have unwrapped
Stack Trace:
java.lang.AssertionError: should have unwrapped
at __randomizedtesting.SeedInfo.seed([2A8122C096CD0913:35E2810573EA491A]: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 14391 lines...]
[junit4] Suite: org.apache.solr.search.TestFiltering
[junit4] 2> 843361 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-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> 843361 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-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_2A8122C096CD0913-001/data-dir-79-001
[junit4] 2> 843361 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 843361 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 843363 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 843363 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 843364 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 843370 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-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> 843387 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 843387 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 843387 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 843390 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 843392 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 843703 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-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> 843705 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-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> 843706 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 843706 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 843707 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1eddd45a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 843707 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1eddd45a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 843710 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 843710 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@33f7f2f7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 843710 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@33f7f2f7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 843712 WARN (SUITE-TestFiltering-seed#[2A8122C096CD0913]-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> 843742 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@751f3cae
[junit4] 2> 843749 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@751f3cae
[junit4] 2> 843749 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@751f3cae
[junit4] 2> 843750 INFO (coreLoadExecutor-9342-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 843759 INFO (coreLoadExecutor-9342-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> 843779 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 843779 WARN (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 843779 WARN (coreLoadExecutor-9342-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> 843782 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 843785 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 843793 INFO (coreLoadExecutor-9342-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> 843794 INFO (coreLoadExecutor-9342-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@751f3cae
[junit4] 2> 843794 INFO (coreLoadExecutor-9342-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_2A8122C096CD0913-001/data-dir-79-001/]
[junit4] 2> 843795 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 843850 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 843850 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 843851 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=16.623046875, floorSegmentMB=1.8115234375, forceMergeDeletesPctAllowed=17.469088837548796, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8745120920889108, deletesPctAllowed=28.55576226545003
[junit4] 2> 843851 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 843857 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 843878 INFO (coreLoadExecutor-9342-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 843879 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 843880 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 843880 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 843881 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843894 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHossssSanity
[junit4] 2> 843894 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 843894 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] 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> 843895 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 843898 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 843898 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 843898 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 843898 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 843899 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 843899 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 843899 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 843899 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 843900 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 843907 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843907 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 843907 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=10 status=0 QTime=0
[junit4] 2> 843909 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] 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=1
[junit4] 2> 843909 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] 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> 843909 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 843910 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] 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> 843910 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] 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> 843911 INFO (TEST-TestFiltering.testHossssSanity-seed#[2A8122C096CD0913]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHossssSanity
[junit4] 2> 843915 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCaching
[junit4] 2> 843915 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 843915 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843916 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 843916 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 843916 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 843916 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 843922 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843922 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 843923 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843923 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843924 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843924 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843924 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843924 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843925 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843926 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843926 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843926 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843927 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843927 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843927 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] 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> 843928 INFO (TEST-TestFiltering.testCaching-seed#[2A8122C096CD0913]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCaching
[junit4] 2> 843933 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomFiltering
[junit4] 2> 843933 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 843933 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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> 843933 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[0]} 0 0
[junit4] 2> 843934 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[1]} 0 0
[junit4] 2> 843934 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[1]} 0 0
[junit4] 2> 843941 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843941 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 843942 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[2]} 0 0
[junit4] 2> 843942 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[3]} 0 0
[junit4] 2> 843948 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843948 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 843949 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[4]} 0 0
[junit4] 2> 843949 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[4]} 0 0
[junit4] 2> 843949 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[5]} 0 0
[junit4] 2> 843949 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[6]} 0 0
[junit4] 2> 843949 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[7]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[8]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[9]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[10]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[11]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[12]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[13]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[14]} 0 0
[junit4] 2> 843950 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[15]} 0 0
[junit4] 2> 843956 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843957 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 843957 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[16]} 0 0
[junit4] 2> 843957 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[17]} 0 0
[junit4] 2> 843957 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[18]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[18]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[19]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[20]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[21]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[22]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[23]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[24]} 0 0
[junit4] 2> 843958 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[25]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[25]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[26]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[27]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[28]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[28]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[29]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[30]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[30]} 0 0
[junit4] 2> 843959 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[31]} 0 0
[junit4] 2> 843960 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[32]} 0 0
[junit4] 2> 843960 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[33]} 0 0
[junit4] 2> 843960 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[34]} 0 0
[junit4] 2> 843960 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[35]} 0 0
[junit4] 2> 843960 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[35]} 0 0
[junit4] 2> 843960 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[36]} 0 0
[junit4] 2> 843966 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843967 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 843967 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[37]} 0 0
[junit4] 2> 843967 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[37]} 0 0
[junit4] 2> 843967 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[38]} 0 0
[junit4] 2> 843968 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[39]} 0 0
[junit4] 2> 843968 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[40]} 0 0
[junit4] 2> 843968 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[41]} 0 0
[junit4] 2> 843968 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[42]} 0 0
[junit4] 2> 843968 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[43]} 0 0
[junit4] 2> 843968 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[43]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[44]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[45]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[46]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[47]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[48]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[48]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[49]} 0 0
[junit4] 2> 843969 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[50]} 0 0
[junit4] 2> 843970 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[50]} 0 0
[junit4] 2> 843970 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[51]} 0 0
[junit4] 2> 843970 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[52]} 0 0
[junit4] 2> 843976 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843976 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 843977 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[53]} 0 0
[junit4] 2> 843983 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 843983 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 843983 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=54 status=0 QTime=0
[junit4] 2> 843987 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:4+-val_i:5+-val_i:9+-val_i:11+-val_i:14+-val_i:16+-val_i:19+-val_i:20+-val_i:23+-val_i:24+-val_i:26+-val_i:27+-val_i:28+-val_i:31+-val_i:34+-val_i:35+-val_i:37+-val_i:38+-val_i:41+-val_i:42+-val_i:45+-val_i:46+-val_i:47&q={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D8+u%3D53}"&fq={!cache%3Dtrue}+-val_i:0+-val_i:1+-val_i:2+-val_i:4+-val_i:6+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:14+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:29+-val_i:31+-val_i:33+-val_i:34+-val_i:35+-val_i:41+-val_i:42+-val_i:43+-val_i:45+-val_i:46+-val_i:47+-val_i:48+-val_i:50+-val_i:51&facet=true&wt=xml} hits=2 status=0 QTime=2
[junit4] 2> 843988 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dtrue+tag%3Dt}val_i:[00008+TO+00020}&group.main=true&wt=xml&group.field=id&group=true} hits=12 status=0 QTime=1
[junit4] 2> 843996 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse}val_i:{00041+TO+00096}+AND+val_i:[00042+TO+00095]&fq={!cache%3Dfalse}+-val_i:1+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:9+-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:23+-val_i:25+-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:40+-val_i:41+-val_i:42+-val_i:43+-val_i:45+-val_i:49+-val_i:50+-val_i:52+-val_i:53&fq={!cache%3Dtrue}+val_i:4+val_i:5+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:21+val_i:22+val_i:32+val_i:33+val_i:35+val_i:37+val_i:38+val_i:39+val_i:40+val_i:41+val_i:42+val_i:45+val_i:46+val_i:48+val_i:49+val_i:50&fq={!cache%3Dfalse+cost%3D130}+val_i:1+val_i:4+val_i:6+val_i:7+val_i:10+val_i:11+val_i:12+val_i:13+val_i:17+val_i:19+val_i:22+val_i:23+val_i:25+val_i:26+val_i:27+val_i:28+val_i:30+val_i:36+val_i:37+val_i:38+val_i:40+val_i:41+val_i:42+val_i:43+val_i:44+val_i:46+val_i:48+val_i:49+val_i:53&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:{00021+TO+00053]"&wt=xml} hits=0 status=0 QTime=7
[junit4] 2> 844001 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D178}+val_i:0+val_i:3+val_i:4+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:22+val_i:23+val_i:24+val_i:30+val_i:31+val_i:32+val_i:33+val_i:35+val_i:36+val_i:39+val_i:41+val_i:45+val_i:46+val_i:52&q={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:3+-val_i:4+-val_i:5+-val_i:9+-val_i:13+-val_i:16+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:25+-val_i:26+-val_i:30+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:37+-val_i:40+-val_i:41+-val_i:44+-val_i:48+-val_i:51&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:6+-val_i:8+-val_i:10+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:19+-val_i:20+-val_i:21+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:40+-val_i:41+-val_i:43+-val_i:45+-val_i:47+-val_i:48+-val_i:50&fq={!cache%3Dfalse}+-val_i:0+-val_i:1+-val_i:3+-val_i:6+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:18+-val_i:19+-val_i:23+-val_i:26+-val_i:28+-val_i:29+-val_i:31+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:39+-val_i:40+-val_i:42+-val_i:44+-val_i:46+-val_i:47+-val_i:48+-val_i:50+-val_i:51+-val_i:53&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00037+TO+00053]"&facet=true&wt=xml} hits=2 status=0 QTime=4
[junit4] 2> 844004 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:5+val_i:6+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:19+val_i:20+val_i:21+val_i:25+val_i:26+val_i:28+val_i:29+val_i:30+val_i:32+val_i:34+val_i:36+val_i:37+val_i:38+val_i:39+val_i:40+val_i:41+val_i:42+val_i:45+val_i:49+val_i:50+val_i:52&fq=val_s:[00011+TO+00062]&fq={!cache%3Dfalse+cost%3D17}+val_i:1+val_i:2+val_i:3+val_i:4+val_i:6+val_i:7+val_i:8+val_i:9+val_i:14+val_i:15+val_i:18+val_i:19+val_i:21+val_i:23+val_i:25+val_i:26+val_i:31+val_i:32+val_i:33+val_i:36+val_i:38+val_i:40+val_i:41+val_i:44+val_i:46+val_i:47+val_i:51+val_i:52&fq=-_query_:"val_i:[00016+TO+00054}"&fq={!cache%3Dtrue}+-val_i:1+-val_i:3+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:14+-val_i:15+-val_i:16+-val_i:19+-val_i:20+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:40+-val_i:42+-val_i:46+-val_i:50+-val_i:51+-val_i:52+-val_i:53&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 844004 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!frange+v%3Dval_i+l%3D28+u%3D44+cache%3Dfalse+cost%3D3+tag%3Dt}&fq={!+tag%3Dt}-_query_:"val_s:{00045+TO+00054}"&wt=xml} hits=17 status=0 QTime=0
[junit4] 2> 844009 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D35+u%3D53}"&q={!cache%3Dfalse+cost%3D168+tag%3Dt}+-val_i:0+-val_i:2+-val_i:3+-val_i:7+-val_i:8+-val_i:12+-val_i:13+-val_i:15+-val_i:17+-val_i:18+-val_i:22+-val_i:26+-val_i:27+-val_i:31+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:41+-val_i:45+-val_i:48+-val_i:50&group.main=true&fq={!cache%3Dfalse+cost%3D70}+-val_i:0+-val_i:1+-val_i:6+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:22+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:32+-val_i:35+-val_i:40+-val_i:42+-val_i:43+-val_i:45+-val_i:49+-val_i:53&fq={!+cache%3Dtrue+tag%3Dt}val_s:[00043+TO+00052]+AND+val_s:{00042+TO+00053}&fq=+-val_i:4+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:33+-val_i:35+-val_i:37+-val_i:38+-val_i:39+-val_i:40+-val_i:44+-val_i:48+-val_i:49+-val_i:50+-val_i:52+-val_i:53&facet=true&wt=xml&group.field=id&group=true} hits=3 status=0 QTime=4
[junit4] 2> 844010 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse+cost%3D72+tag%3Dt}val_i:[00050+TO+00080}+AND+val_i:{00049+TO+00079]&fq={!cache%3Dfalse+cost%3D157+tag%3Dt}+val_i:0+val_i:2+val_i:3+val_i:6+val_i:9+val_i:10+val_i:11+val_i:12+val_i:15+val_i:16+val_i:19+val_i:20+val_i:24+val_i:25+val_i:28+val_i:30+val_i:31+val_i:34+val_i:40+val_i:42+val_i:46+val_i:48+val_i:50+val_i:51+val_i:53&fq={!+cache%3Dtrue+tag%3Dt}id:RAND565201012+OR+val_i:{00037+TO+00088}&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 844013 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:1+val_i:4+val_i:5+val_i:7+val_i:8+val_i:11+val_i:13+val_i:15+val_i:17+val_i:19+val_i:22+val_i:26+val_i:27+val_i:29+val_i:30+val_i:32+val_i:38+val_i:40+val_i:42+val_i:46+val_i:47+val_i:48+val_i:50+val_i:51+val_i:52&q=-_query_:"{!frange+v%3Dval_i+l%3D42+u%3D53}"&fq={!frange+v%3Dval_i+l%3D0+u%3D28+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dtrue}+-val_i:0+-val_i:1+-val_i:3+-val_i:5+-val_i:7+-val_i:14+-val_i:15+-val_i:17+-val_i:18+-val_i:22+-val_i:23+-val_i:24+-val_i:29+-val_i:33+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:41+-val_i:43+-val_i:44+-val_i:46+-val_i:48+-val_i:50+-val_i:52&fq=-_query_:"{!frange+v%3Dval_i+l%3D31+u%3D53}"&facet=true&wt=xml} hits=17 status=0 QTime=2
[junit4] 2> 844014 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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_i:[00019+TO+00047]&q={!cache%3Dfalse+cost%3D156+tag%3Dt}+-val_i:0+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:13+-val_i:17+-val_i:18+-val_i:20+-val_i:26+-val_i:27+-val_i:30+-val_i:32+-val_i:33+-val_i:35+-val_i:36+-val_i:37+-val_i:41+-val_i:42+-val_i:43+-val_i:45+-val_i:50+-val_i:51&facet=true&wt=xml} hits=25 status=0 QTime=1
[junit4] 2> 844015 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D62+cache%3Dfalse}&q={!cache%3Dtrue+tag%3Dt}+val_i:2+val_i:3+val_i:4+val_i:6+val_i:7+val_i:8+val_i:11+val_i:12+val_i:13+val_i:14+val_i:22+val_i:23+val_i:24+val_i:25+val_i:26+val_i:27+val_i:28+val_i:29+val_i:36+val_i:38+val_i:40+val_i:41+val_i:44+val_i:45+val_i:47+val_i:48+val_i:49+val_i:51+val_i:52&facet=true&wt=xml} hits=29 status=0 QTime=1
[junit4] 2> 844018 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue+tag%3Dt}+val_i:4+val_i:8+val_i:10+val_i:11+val_i:13+val_i:18+val_i:21+val_i:22+val_i:24+val_i:26+val_i:29+val_i:31+val_i:33+val_i:35+val_i:39+val_i:40+val_i:42+val_i:43+val_i:44+val_i:47+val_i:48+val_i:51+val_i:52&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-val_i:9+-val_i:10+-val_i:12+-val_i:13+-val_i:14+-val_i:19+-val_i:22+-val_i:26+-val_i:31+-val_i:33+-val_i:40+-val_i:41+-val_i:45+-val_i:46+-val_i:47+-val_i:48+-val_i:49+-val_i:51+-val_i:52+-val_i:53&fq={!cache%3Dfalse+tag%3Dt}+val_i:2+val_i:3+val_i:4+val_i:5+val_i:7+val_i:8+val_i:12+val_i:13+val_i:14+val_i:15+val_i:16+val_i:19+val_i:21+val_i:23+val_i:24+val_i:26+val_i:30+val_i:32+val_i:33+val_i:34+val_i:35+val_i:36+val_i:39+val_i:40+val_i:41+val_i:43+val_i:46+val_i:47&wt=xml} hits=6 status=0 QTime=2
[junit4] 2> 844020 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:4+val_i:6+val_i:7+val_i:9+val_i:10+val_i:12+val_i:14+val_i:15+val_i:16+val_i:17+val_i:19+val_i:21+val_i:23+val_i:26+val_i:27+val_i:28+val_i:29+val_i:31+val_i:33+val_i:34+val_i:36+val_i:37+val_i:39+val_i:43+val_i:48+val_i:51+val_i:52&q={!frange+v%3Dval_i+l%3D5+u%3D45+cache%3Dtrue+tag%3Dt}&fq={!+cache%3Dfalse}-_query_:"val_i:{00038+TO+00053]"&fq={!+cache%3Dtrue+tag%3Dt}val_s:[00050+TO+00064]&fq={!cache%3Dfalse+cost%3D69}+val_i:0+val_i:1+val_i:3+val_i:4+val_i:9+val_i:10+val_i:12+val_i:13+val_i:14+val_i:16+val_i:17+val_i:18+val_i:19+val_i:24+val_i:25+val_i:28+val_i:30+val_i:31+val_i:34+val_i:39+val_i:40+val_i:41+val_i:42+val_i:43+val_i:44+val_i:46+val_i:50+val_i:52&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D44+u%3D53}"&facet=true&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 844022 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D53+u%3D53}&q={!+cache%3Dtrue+tag%3Dt}val_i:{00034+TO+00058]&fq=-_query_:"{!frange+v%3Dval_i+l%3D3+u%3D53}"&fq={!+cost%3D134}val_s:{00039+TO+00059]&fq={!cache%3Dfalse}+-val_i:0+-val_i:2+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:26+-val_i:27+-val_i:30+-val_i:34+-val_i:35+-val_i:38+-val_i:39+-val_i:41+-val_i:43+-val_i:44+-val_i:47+-val_i:51+-val_i:52+-val_i:53&facet=true&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 844023 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:{00017+TO+00069]+OR+val_i:[00018+TO+00070}&q=-_query_:"{!frange+v%3Dval_i+l%3D44+u%3D53}"&facet=true&wt=xml} hits=44 status=0 QTime=0
[junit4] 2> 844024 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D37+u%3D53}"&q={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:[00013+TO+00053]"&fq={!cache%3Dfalse+tag%3Dt}+-val_i:0+-val_i:6+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:15+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:24+-val_i:28+-val_i:41+-val_i:43+-val_i:44+-val_i:46+-val_i:48+-val_i:50+-val_i:51+-val_i:52+-val_i:53&facet=true&wt=xml} hits=6 status=0 QTime=1
[junit4] 2> 844026 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:6+-val_i:12+-val_i:16+-val_i:17+-val_i:18+-val_i:20+-val_i:21+-val_i:24+-val_i:25+-val_i:27+-val_i:29+-val_i:30+-val_i:36+-val_i:37+-val_i:39+-val_i:44+-val_i:48+-val_i:52+-val_i:53&fq=id:RAND-1071592844+OR+val_i:{00039+TO+00076}&fq={!}val_s:[00052+TO+00102]&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 844026 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse}-_query_:"val_s:{00026+TO+00054}"&fq={!frange+v%3Dval_i+l%3D15+u%3D66+tag%3Dt}&wt=xml} hits=12 status=0 QTime=0
[junit4] 2> 844029 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D84}+val_i:0+val_i:1+val_i:2+val_i:5+val_i:6+val_i:9+val_i:10+val_i:11+val_i:12+val_i:13+val_i:14+val_i:15+val_i:17+val_i:18+val_i:21+val_i:22+val_i:25+val_i:27+val_i:29+val_i:30+val_i:31+val_i:32+val_i:34+val_i:35+val_i:37+val_i:40+val_i:41+val_i:43+val_i:44+val_i:46+val_i:47+val_i:48+val_i:49+val_i:53&q={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:6+val_i:8+val_i:10+val_i:12+val_i:13+val_i:15+val_i:17+val_i:18+val_i:19+val_i:22+val_i:24+val_i:25+val_i:26+val_i:27+val_i:33+val_i:36+val_i:39+val_i:40+val_i:41+val_i:42+val_i:43+val_i:46+val_i:48+val_i:50+val_i:53&fq=*:*+AND+val_s:{00013+TO+00046]&fq={!frange+v%3Dval_i+l%3D23+u%3D70}&fq={!+cache%3Dfalse+cost%3D109+tag%3Dt}-_query_:"val_s:{00015+TO+00053]"&fq={!cache%3Dfalse+cost%3D140}+-val_i:1+-val_i:3+-val_i:4+-val_i:7+-val_i:9+-val_i:10+-val_i:12+-val_i:13+-val_i:16+-val_i:22+-val_i:25+-val_i:26+-val_i:29+-val_i:34+-val_i:37+-val_i:39+-val_i:44+-val_i:47+-val_i:50+-val_i:52+-val_i:53&facet=true&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 844033 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:5+val_i:6+val_i:7+val_i:9+val_i:11+val_i:12+val_i:16+val_i:17+val_i:18+val_i:19+val_i:21+val_i:25+val_i:27+val_i:29+val_i:30+val_i:31+val_i:35+val_i:36+val_i:37+val_i:40+val_i:43+val_i:46+val_i:47+val_i:49+val_i:50+val_i:51+val_i:52+val_i:53&q={!cache%3Dfalse+cost%3D158}+-val_i:4+-val_i:7+-val_i:10+-val_i:12+-val_i:15+-val_i:16+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:25+-val_i:26+-val_i:27+-val_i:28+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:37+-val_i:38+-val_i:39+-val_i:41+-val_i:43+-val_i:45+-val_i:46+-val_i:47+-val_i:48+-val_i:49+-val_i:51+-val_i:52&fq={!}val_i:{00021+TO+00057]+OR+id:RAND2094395931&fq=-_query_:"{!frange+v%3Dval_i+l%3D43+u%3D53}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:2+-val_i:5+-val_i:8+-val_i:9+-val_i:11+-val_i:13+-val_i:15+-val_i:16+-val_i:18+-val_i:19+-val_i:20+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:38+-val_i:39+-val_i:40+-val_i:42+-val_i:43+-val_i:45+-val_i:46+-val_i:47+-val_i:48+-val_i:53&facet=true&wt=xml} hits=3 status=0 QTime=3
[junit4] 2> 844035 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+tag%3Dt}+val_i:0+val_i:1+val_i:3+val_i:4+val_i:5+val_i:8+val_i:10+val_i:18+val_i:19+val_i:23+val_i:25+val_i:29+val_i:31+val_i:34+val_i:35+val_i:37+val_i:38+val_i:39+val_i:41+val_i:42+val_i:43+val_i:44+val_i:45+val_i:46&fq=id:RAND1655043239+OR+val_s:[00007+TO+00061}&fq={!+cost%3D78+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D39+u%3D53}"&wt=xml} hits=12 status=0 QTime=1
[junit4] 2> 844037 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue}+val_i:0+val_i:2+val_i:10+val_i:11+val_i:14+val_i:15+val_i:16+val_i:17+val_i:20+val_i:21+val_i:23+val_i:24+val_i:26+val_i:27+val_i:28+val_i:29+val_i:30+val_i:31+val_i:33+val_i:34+val_i:37+val_i:40+val_i:41+val_i:42+val_i:43+val_i:47+val_i:48+val_i:51+val_i:52&fq={!+tag%3Dt}val_i:[00028+TO+00080]&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D49+u%3D53}"&fq={!cache%3Dfalse+cost%3D97}+-val_i:1+-val_i:2+-val_i:3+-val_i:7+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:24+-val_i:25+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:35+-val_i:37+-val_i:38+-val_i:39+-val_i:41+-val_i:42+-val_i:46+-val_i:48+-val_i:49+-val_i:51+-val_i:53&fq={!frange+v%3Dval_i+l%3D11+u%3D25+cache%3Dfalse+cost%3D143}&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 844037 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!frange+v%3Dval_i+l%3D10+u%3D62+cache%3Dfalse+cost%3D22}&wt=xml} hits=44 status=0 QTime=0
[junit4] 2> 844040 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:4+val_i:5+val_i:10+val_i:11+val_i:17+val_i:21+val_i:26+val_i:29+val_i:32+val_i:35+val_i:38+val_i:42+val_i:43+val_i:44+val_i:45+val_i:46+val_i:48+val_i:49+val_i:51+val_i:52&q={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:{00045+TO+00054}"&fq=+-val_i:1+-val_i:2+-val_i:3+-val_i:5+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:14+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:29+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:37+-val_i:40+-val_i:41+-val_i:42+-val_i:44+-val_i:46+-val_i:47+-val_i:48+-val_i:49+-val_i:50+-val_i:51+-val_i:52&fq={!cache%3Dfalse+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:4+val_i:5+val_i:6+val_i:8+val_i:10+val_i:13+val_i:14+val_i:15+val_i:16+val_i:17+val_i:20+val_i:23+val_i:24+val_i:25+val_i:27+val_i:32+val_i:33+val_i:35+val_i:36+val_i:37+val_i:39+val_i:45+val_i:46+val_i:47+val_i:48+val_i:49+val_i:50+val_i:52+val_i:53&facet=true&wt=xml} hits=13 status=0 QTime=2
[junit4] 2> 844042 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dtrue+tag%3Dt}val_i:[00025+TO+00048}+AND+val_i:{00024+TO+00047]&fq={!cache%3Dfalse+cost%3D162+tag%3Dt}+-val_i:1+-val_i:3+-val_i:4+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:16+-val_i:17+-val_i:20+-val_i:21+-val_i:25+-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:37+-val_i:38+-val_i:41+-val_i:42+-val_i:43+-val_i:45+-val_i:47+-val_i:48+-val_i:52+-val_i:53&fq={!+cache%3Dtrue+tag%3Dt}val_s:{00007+TO+00046]&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:4+val_i:5+val_i:6+val_i:8+val_i:9+val_i:11+val_i:13+val_i:14+val_i:16+val_i:17+val_i:19+val_i:21+val_i:22+val_i:25+val_i:27+val_i:29+val_i:35+val_i:36+val_i:39+val_i:42+val_i:43+val_i:45+val_i:46+val_i:48+val_i:51+val_i:52&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 844045 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:2+val_i:4+val_i:5+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:18+val_i:19+val_i:20+val_i:21+val_i:23+val_i:24+val_i:29+val_i:30+val_i:36+val_i:37+val_i:38+val_i:40+val_i:41+val_i:42+val_i:43+val_i:44+val_i:46+val_i:48+val_i:49+val_i:52+val_i:53&q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:2+-val_i:6+-val_i:10+-val_i:15+-val_i:16+-val_i:17+-val_i:21+-val_i:25+-val_i:27+-val_i:28+-val_i:30+-val_i:31+-val_i:37+-val_i:38+-val_i:39+-val_i:41+-val_i:43+-val_i:44+-val_i:47+-val_i:48+-val_i:49+-val_i:50+-val_i:51+-val_i:52+-val_i:53&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00008+TO+00027}+OR+val_i:{00007+TO+00026]&fq=*:*+AND+val_i:{00014+TO+00042}&fq=-_query_:"val_s:{00044+TO+00053]"&facet=true&wt=xml} hits=7 status=0 QTime=2
[junit4] 2> 844048 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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_s:{00037+TO+00074]+OR+val_s:[00038+TO+00075}&q=+-val_i:3+-val_i:6+-val_i:9+-val_i:13+-val_i:14+-val_i:17+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:33+-val_i:34+-val_i:36+-val_i:37+-val_i:40+-val_i:41+-val_i:42+-val_i:43+-val_i:44+-val_i:45+-val_i:46+-val_i:48+-val_i:49&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00040+TO+00053]"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:4+-val_i:6+-val_i:7+-val_i:12+-val_i:13+-val_i:15+-val_i:16+-val_i:21+-val_i:22+-val_i:24+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:31+-val_i:33+-val_i:37+-val_i:39+-val_i:41+-val_i:42+-val_i:43+-val_i:44+-val_i:45+-val_i:46+-val_i:48+-val_i:49+-val_i:53&fq={!frange+v%3Dval_i+l%3D22+u%3D59}&facet=true&wt=xml} hits=3 status=0 QTime=3
[junit4] 2> 844050 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+tag%3Dt}+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:15+-val_i:16+-val_i:17+-val_i:22+-val_i:24+-val_i:28+-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:41+-val_i:42+-val_i:45+-val_i:46+-val_i:49+-val_i:50+-val_i:52+-val_i:53&fq={!+cost%3D153}-_query_:"val_i:[00053+TO+00054]"&fq={!frange+v%3Dval_i+l%3D43+u%3D69}&wt=xml} hits=5 status=0 QTime=1
[junit4] 2> 844060 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!frange+v%3Dval_i+l%3D37+u%3D78+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:2+val_i:4+val_i:7+val_i:8+val_i:11+val_i:12+val_i:13+val_i:14+val_i:15+val_i:18+val_i:20+val_i:23+val_i:24+val_i:25+val_i:29+val_i:30+val_i:33+val_i:34+val_i:35+val_i:37+val_i:41+val_i:43+val_i:44+val_i:46+val_i:49+val_i:51+val_i:52+val_i:53&fq={!+cost%3D152}-_query_:"val_s:[00033+TO+00053]"&fq={!+cost%3D12+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D22+u%3D53}"&fq={!cache%3Dfalse+cost%3D104}+val_i:0+val_i:4+val_i:5+val_i:9+val_i:11+val_i:12+val_i:13+val_i:18+val_i:19+val_i:20+val_i:25+val_i:27+val_i:29+val_i:30+val_i:31+val_i:34+val_i:35+val_i:36+val_i:37+val_i:38+val_i:40+val_i:42+val_i:44+val_i:45+val_i:46+val_i:47+val_i:50+val_i:52&wt=xml} hits=0 status=0 QTime=9
[junit4] 2> 844062 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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_s:[00024+TO+00057}+OR+val_s:{00023+TO+00056]&q={!cache%3Dfalse+cost%3D158}+val_i:0+val_i:3+val_i:6+val_i:7+val_i:11+val_i:12+val_i:14+val_i:16+val_i:18+val_i:19+val_i:22+val_i:25+val_i:27+val_i:28+val_i:30+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:46+val_i:48+val_i:49+val_i:50+val_i:51+val_i:52&fq={!+cache%3Dfalse+cost%3D195}-_query_:"{!frange+v%3Dval_i+l%3D4+u%3D53}"&fq={!frange+v%3Dval_i+l%3D19+u%3D50+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 844062 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-_query_:"val_i:{00031+TO+00053]"&wt=xml} hits=32 status=0 QTime=0
[junit4] 2> 844063 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-_query_:"val_i:{00008+TO+00053]"&fq={!+cache%3Dfalse+cost%3D60}-_query_:"{!frange+v%3Dval_i+l%3D6+u%3D53}"&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 844064 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse}+-val_i:0+-val_i:3+-val_i:5+-val_i:11+-val_i:13+-val_i:14+-val_i:15+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:23+-val_i:28+-val_i:30+-val_i:31+-val_i:32+-val_i:37+-val_i:40+-val_i:43+-val_i:44+-val_i:46+-val_i:47+-val_i:48+-val_i:50+-val_i:52+-val_i:53&fq={!frange+v%3Dval_i+l%3D33+u%3D55}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:{00048+TO+00054}"&wt=xml} hits=9 status=0 QTime=1
[junit4] 2> 844067 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D51+u%3D73}&q={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:4+val_i:12+val_i:13+val_i:14+val_i:17+val_i:18+val_i:19+val_i:20+val_i:22+val_i:23+val_i:27+val_i:28+val_i:31+val_i:34+val_i:36+val_i:40+val_i:41+val_i:42+val_i:46+val_i:47+val_i:48+val_i:49+val_i:50&fq={!+cache%3Dfalse+cost%3D18}-_query_:"{!frange+v%3Dval_i+l%3D24+u%3D53}"&fq={!+cost%3D149}-_query_:"{!frange+v%3Dval_i+l%3D10+u%3D53}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:4+-val_i:7+-val_i:8+-val_i:9+-val_i:11+-val_i:12+-val_i:14+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:30+-val_i:31+-val_i:34+-val_i:35+-val_i:36+-val_i:39+-val_i:41+-val_i:42+-val_i:44+-val_i:45+-val_i:46+-val_i:50+-val_i:52+-val_i:53&fq={!cache%3Dtrue+tag%3Dt}+val_i:2+val_i:3+val_i:5+val_i:7+val_i:9+val_i:10+val_i:15+val_i:16+val_i:18+val_i:19+val_i:20+val_i:22+val_i:23+val_i:27+val_i:28+val_i:29+val_i:31+val_i:34+val_i:37+val_i:41+val_i:43+val_i:44+val_i:45+val_i:46+val_i:47+val_i:49+val_i:51+val_i:53&facet=true&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 844069 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!frange+v%3Dval_i+l%3D19+u%3D36+cache%3Dfalse+tag%3Dt}&fq={!frange+v%3Dval_i+l%3D33+u%3D49}&fq={!+cache%3Dfalse+cost%3D109+tag%3Dt}-_query_:"val_s:[00028+TO+00053]"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:3+-val_i:4+-val_i:8+-val_i:9+-val_i:10+-val_i:13+-val_i:14+-val_i:16+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:26+-val_i:28+-val_i:29+-val_i:30+-val_i:31+-val_i:34+-val_i:35+-val_i:38+-val_i:39+-val_i:40+-val_i:42+-val_i:45+-val_i:51&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 844072 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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%3D188}+val_i:0+val_i:2+val_i:5+val_i:6+val_i:7+val_i:9+val_i:11+val_i:12+val_i:13+val_i:16+val_i:17+val_i:18+val_i:19+val_i:20+val_i:21+val_i:26+val_i:29+val_i:30+val_i:35+val_i:36+val_i:37+val_i:38+val_i:45+val_i:46+val_i:48+val_i:51+val_i:52+val_i:53&q={!cache%3Dfalse}+-val_i:0+-val_i:1+-val_i:2+-val_i:4+-val_i:5+-val_i:8+-val_i:12+-val_i:13+-val_i:17+-val_i:18+-val_i:21+-val_i:23+-val_i:25+-val_i:27+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:36+-val_i:42+-val_i:43+-val_i:44+-val_i:45+-val_i:48+-val_i:50+-val_i:52&fq={!cache%3Dfalse+cost%3D106+tag%3Dt}+-val_i:0+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:9+-val_i:10+-val_i:12+-val_i:13+-val_i:14+-val_i:19+-val_i:21+-val_i:27+-val_i:30+-val_i:31+-val_i:35+-val_i:38+-val_i:40+-val_i:41+-val_i:42+-val_i:43+-val_i:44+-val_i:45+-val_i:46+-val_i:47+-val_i:48+-val_i:51&facet=true&wt=xml} hits=16 status=0 QTime=2
[junit4] 2> 844073 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-_query_:"{!frange+v%3Dval_i+l%3D22+u%3D53}"&fq={!frange+v%3Dval_i+l%3D21+u%3D73+cache%3Dfalse}&fq={!cache%3Dfalse+tag%3Dt}+-val_i:0+-val_i:4+-val_i:6+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:13+-val_i:15+-val_i:16+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:37+-val_i:39+-val_i:41+-val_i:42+-val_i:44+-val_i:47+-val_i:48+-val_i:50+-val_i:51+-val_i:52&fq={!+cache%3Dfalse+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D42+u%3D53}"&fq={!+cache%3Dfalse+cost%3D37+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D39+u%3D53}"&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 844075 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:2+val_i:4+val_i:6+val_i:7+val_i:8+val_i:9+val_i:12+val_i:13+val_i:16+val_i:17+val_i:18+val_i:19+val_i:22+val_i:23+val_i:24+val_i:25+val_i:26+val_i:30+val_i:31+val_i:34+val_i:36+val_i:39+val_i:40+val_i:41+val_i:45+val_i:49+val_i:50+val_i:51+val_i:53&fq=-_query_:"{!frange+v%3Dval_i+l%3D18+u%3D53}"&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00046+TO+00053]"&fq=-_query_:"val_i:{00018+TO+00053]"&fq=-_query_:"{!frange+v%3Dval_i+l%3D9+u%3D53}"&wt=xml} hits=6 status=0 QTime=1
[junit4] 2> 844076 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!}val_i:[00036+TO+00056]+OR+id:RAND-240504780&fq={!+tag%3Dt}-_query_:"val_i:{00029+TO+00053]"&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:3+val_i:10+val_i:11+val_i:12+val_i:14+val_i:15+val_i:16+val_i:17+val_i:20+val_i:22+val_i:23+val_i:24+val_i:28+val_i:30+val_i:35+val_i:39+val_i:40+val_i:43+val_i:45+val_i:46+val_i:48+val_i:49+val_i:50+val_i:53&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 844076 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse+cost%3D97+tag%3Dt}val_s:[00019+TO+00036]&wt=xml} hits=18 status=0 QTime=0
[junit4] 2> 844079 INFO (TEST-TestFiltering.testRandomFiltering-seed#[2A8122C096CD0913]) [ ] 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:[00017+TO+00053]"&q={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:4+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:13+-val_i:14+-val_i:18+-val_i:20+-val_i:25+-val_i:28+-val_i:30+-val_i:32+-val_i:33+-val_i:34+-val_i:37+-val_i:41+-val_i:46+-val_i:47+-val_i:49+-val_i:51+-val_i:52+-val_i:53&fq={!+tag%3Dt}-_query_:"val_s:{00013+TO+00053]"&fq={!+cache%3Dtrue+tag%3Dt}val_i:{00001+TO+00024]+OR+val_i:[00002+TO+00025}&fq={!+cache%3Dfalse+cost%3D132}-_query_:"{!frange+v%3Dval_i+l%3D7+u%3D53}"&facet=true&wt=xml} hits=3 status=0 QTime=2
[junit
[...truncated too long message...]
eed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 844453 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 844453 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 844453 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 844454 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 844454 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 844454 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 844455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 844455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 844455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 0
[junit4] 2> 844456 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 0
[junit4] 2> 844456 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 0
[junit4] 2> 844456 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 0
[junit4] 2> 844457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 844457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 844457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 0
[junit4] 2> 844458 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17]} 0 0
[junit4] 2> 844458 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[18]} 0 0
[junit4] 2> 844458 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[19]} 0 0
[junit4] 2> 844465 INFO (searcherExecutor-9344-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 844468 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 844472 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[2A8122C096CD0913]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLiveDocsSharing
[junit4] 2> 844472 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 844472 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1291584221
[junit4] 2> 844476 INFO (coreCloseExecutor-9349-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@80a8918
[junit4] 2> 844476 INFO (coreCloseExecutor-9349-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@80a8918
[junit4] 2> 844476 INFO (coreCloseExecutor-9349-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f3d4a87: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@29c02f97
[junit4] 2> 844496 INFO (coreCloseExecutor-9349-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 844498 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 844498 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@54bd9e20: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@40b9daea
[junit4] 2> 844503 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 844503 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@dcf2a1e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5d2db6ba
[junit4] 2> 844506 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 844506 INFO (SUITE-TestFiltering-seed#[2A8122C096CD0913]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5758437e: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@2dad6a9b
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_2A8122C096CD0913-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {val_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), _root_=PostingsFormat(name=LuceneFixedGap), val_s=Lucene84, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), foo_s=PostingsFormat(name=LuceneFixedGap)}, docValues:{val_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=359, maxMBSortInHeap=7.0753556939636155, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=en-IN, timezone=Asia/Katmandu
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=195324064,total=518520832
[junit4] 2> NOTE: All tests run in this JVM: [TestCaffeineCache, SynonymTokenizerTest, TestCrossCoreJoin, TestSolrCloudWithDelegationTokens, TestApiFramework, BlockDirectoryTest, RoutingToNodesWithPropertiesTest, IndexBasedSpellCheckerTest, TestShardHandlerFactory, TestConfigSetsAPIZkFailure, TestExportTool, NestedShardedAtomicUpdateTest, AssignTest, TestNumericRangeQuery64, RankQueryTest, HdfsWriteToMultipleCollectionsTest, TestFieldCacheSort, ZkStateReaderTest, TestScoreJoinQPNoScore, RegexBoostProcessorTest, DocValuesNotIndexedTest, FileUtilsTest, SolrRequestParserTest, TestFieldTypeCollectionResource, ProtectedTermFilterFactoryTest, BasicFunctionalityTest, CollectionsAPISolrJTest, DocValuesMultiTest, TestCorePropertiesReload, JWTIssuerConfigTest, TestHdfsBackupRestoreCore, SyncSliceTest, TestGeoJSONResponseWriter, ResponseLogComponentTest, TestExceedMaxTermLength, IndexSizeTriggerTest, CloudMLTQParserTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, TestSolrCloudSnapshots, TestLRUStatsCache, TestHttpShardHandlerFactory, HdfsDirectoryFactoryTest, TestConfLoadPerf, TestRTGBase, TestSystemIdResolver, SortSpecParsingTest, TestRandomRequestDistribution, TestJoin, LeaderElectionIntegrationTest, TestMinMaxOnMultiValuedField, LeaderTragicEventTest, MetricsConfigTest, HLLUtilTest, SearchRateTriggerIntegrationTest, AutoAddReplicasPlanActionTest, TestSolrCoreParser, LukeRequestHandlerTest, TestPushWriter, SimpleFacetsTest, AssignBackwardCompatibilityTest, PreAnalyzedFieldTest, TestWithCollection, JavaBinAtomicUpdateMultivalueTest, SolrIndexConfigTest, TestSmileRequest, TestCloudJSONFacetJoinDomain, VersionInfoTest, RestoreTriggerStateTest, UtilsToolTest, TestSolrCloudWithHadoopAuthPlugin, CustomTermsComponentTest, MoveReplicaHDFSTest, TestSort, SolrCoreTest, OverseerCollectionConfigSetProcessorTest, HdfsNNFailoverTest, TestFiltering]
[junit4] Completed [314/947 (1!)] on J1 in 1.26s, 4 tests, 1 failure <<< FAILURES!
[...truncated 56410 lines...]