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/12/12 01:23:26 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.11 - Build # 129 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.11/129/
2 tests failed.
FAILED: org.apache.solr.search.TestFiltering.testRandomFiltering
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([9439114A4578DFFC]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestFiltering
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([9439114A4578DFFC]:0)
Build Log:
[...truncated 16381 lines...]
[junit4] Suite: org.apache.solr.search.TestFiltering
[junit4] 2> 507460 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-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.11/solr/server/solr/configsets/_default/conf'
[junit4] 2> 507460 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-66 after mutting 0 log messages
[junit4] 2> 507460 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-67 for ERROR logs matching regex: ignore_exception
[junit4] 2> 507461 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_9439114A4578DFFC-001/data-dir-33-001
[junit4] 2> 507461 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 507461 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 507463 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 507463 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 507464 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 507470 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 507485 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.1
[junit4] 2> 507485 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 507485 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 507488 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 507491 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 508204 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/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.11/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.11/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:830) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:820) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:667) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:656) [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> 508205 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/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.11/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.11/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:683) [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:830) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:820) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:667) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:656) [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> 508208 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 508208 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-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> 508210 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2363e605[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 508210 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2363e605[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 508216 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-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> 508216 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42ce0354[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 508216 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42ce0354[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 508218 WARN (SUITE-TestFiltering-seed#[9439114A4578DFFC]-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> 508248 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f3fc10a
[junit4] 2> 508255 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f3fc10a
[junit4] 2> 508255 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f3fc10a
[junit4] 2> 508256 INFO (coreLoadExecutor-6385-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 508265 INFO (coreLoadExecutor-6385-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.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 508282 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.1
[junit4] 2> 508282 WARN (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 508283 WARN (coreLoadExecutor-6385-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> 508285 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 508288 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 508295 INFO (coreLoadExecutor-6385-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.11/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 508295 INFO (coreLoadExecutor-6385-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@7f3fc10a
[junit4] 2> 508295 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_9439114A4578DFFC-001/data-dir-33-001/]
[junit4] 2> 508296 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6758375236103309]
[junit4] 2> 508364 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 508364 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 508365 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8820674205171198]
[junit4] 2> 508366 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 508372 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 508397 INFO (coreLoadExecutor-6385-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 508397 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 508397 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 508397 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 508397 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 508397 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 508397 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 508398 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 508398 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 508398 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 508399 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508401 INFO (SUITE-TestFiltering-seed#[9439114A4578DFFC]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 508405 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCaching
[junit4] 2> 508405 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 508405 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508406 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 508408 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 508408 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 508409 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 508415 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508416 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 508417 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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=1
[junit4] 2> 508418 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508418 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508419 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508419 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508419 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508434 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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=14
[junit4] 2> 508435 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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=1
[junit4] 2> 508436 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508437 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508437 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508437 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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> 508439 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] 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=1
[junit4] 2> 508440 INFO (TEST-TestFiltering.testCaching-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCaching
[junit4] 2> 508448 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLiveDocsSharing
[junit4] 2> 508448 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 508448 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ ] 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> 508452 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 3
[junit4] 2> 508452 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 508453 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 508453 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 508453 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 508454 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 508454 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 508454 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 508455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 508455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 508455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 508455 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 508456 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 508456 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 508456 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 0
[junit4] 2> 508457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 0
[junit4] 2> 508457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 0
[junit4] 2> 508457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 0
[junit4] 2> 508457 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 508458 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 508458 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 0
[junit4] 2> 508458 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17]} 0 0
[junit4] 2> 508459 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[18]} 0 0
[junit4] 2> 508459 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[19]} 0 0
[junit4] 2> 508476 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 2 ms
[junit4] 2> 508477 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 508485 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLiveDocsSharing
[junit4] 2> 508488 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHossssSanity
[junit4] 2> 508488 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 508488 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] 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> 508489 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 508489 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 508490 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 508490 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 508491 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 508492 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 508492 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 508492 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 508493 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 508494 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 508500 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 1 ms
[junit4] 2> 508503 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 508503 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=10 status=0 QTime=0
[junit4] 2> 508506 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] 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> 508506 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] 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> 508510 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 508510 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] 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> 508510 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] 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> 508511 INFO (TEST-TestFiltering.testHossssSanity-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHossssSanity
[junit4] 2> 508517 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomFiltering
[junit4] 2> 508517 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 508517 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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> 508520 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[0]} 0 2
[junit4] 2> 508520 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[1]} 0 0
[junit4] 2> 508520 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[2]} 0 0
[junit4] 2> 508533 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508534 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 508535 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[3]} 0 0
[junit4] 2> 508535 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[4]} 0 0
[junit4] 2> 508535 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[4]} 0 0
[junit4] 2> 508535 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[5]} 0 0
[junit4] 2> 508535 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[6]} 0 0
[junit4] 2> 508535 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[7]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[8]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[8]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[9]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[10]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[11]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[12]} 0 0
[junit4] 2> 508536 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[13]} 0 0
[junit4] 2> 508537 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[14]} 0 0
[junit4] 2> 508553 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508553 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 16
[junit4] 2> 508554 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[15]} 0 0
[junit4] 2> 508554 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[15]} 0 0
[junit4] 2> 508555 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[16]} 0 0
[junit4] 2> 508555 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[17]} 0 0
[junit4] 2> 508555 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[18]} 0 0
[junit4] 2> 508556 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[19]} 0 0
[junit4] 2> 508566 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508567 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 11
[junit4] 2> 508576 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[20]} 0 9
[junit4] 2> 508576 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[20]} 0 0
[junit4] 2> 508576 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[21]} 0 0
[junit4] 2> 508577 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[22]} 0 0
[junit4] 2> 508577 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[23]} 0 0
[junit4] 2> 508577 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[24]} 0 0
[junit4] 2> 508577 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[25]} 0 0
[junit4] 2> 508590 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508590 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 508591 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[26]} 0 0
[junit4] 2> 508591 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[27]} 0 0
[junit4] 2> 508591 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[27]} 0 0
[junit4] 2> 508591 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[28]} 0 0
[junit4] 2> 508592 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[29]} 0 0
[junit4] 2> 508592 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[30]} 0 0
[junit4] 2> 508592 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[31]} 0 0
[junit4] 2> 508592 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[32]} 0 0
[junit4] 2> 508592 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[33]} 0 0
[junit4] 2> 508592 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[34]} 0 0
[junit4] 2> 508599 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508599 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[35]} 0 0
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[35]} 0 0
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[36]} 0 0
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[37]} 0 0
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[37]} 0 0
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[38]} 0 0
[junit4] 2> 508600 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[39]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[39]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[40]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[41]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[42]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[42]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[43]} 0 0
[junit4] 2> 508601 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[43]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[44]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[44]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[45]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[46]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[47]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[48]} 0 0
[junit4] 2> 508602 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[49]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[50]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[50]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[51]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[52]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[52]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[53]} 0 0
[junit4] 2> 508603 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[54]} 0 0
[junit4] 2> 508626 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508627 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 23
[junit4] 2> 508627 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[55]} 0 0
[junit4] 2> 508628 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[56]} 0 0
[junit4] 2> 508628 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[57]} 0 0
[junit4] 2> 508628 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[58]} 0 0
[junit4] 2> 508628 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[58]} 0 0
[junit4] 2> 508628 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[59]} 0 0
[junit4] 2> 508628 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[60]} 0 0
[junit4] 2> 508629 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[61]} 0 0
[junit4] 2> 508636 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 508636 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 508637 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[62]} 0 0
[junit4] 2> 508637 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[63]} 0 0
[junit4] 2> 508637 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[64]} 0 0
[junit4] 2> 508637 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[65]} 0 0
[junit4] 2> 508647 INFO (searcherExecutor-6387-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 2 ms
[junit4] 2> 508652 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 14
[junit4] 2> 508652 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=66 status=0 QTime=0
[junit4] 2> 508659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:3+val_i:4+val_i:5+val_i:6+val_i:11+val_i:13+val_i:16+val_i:17+val_i:19+val_i:21+val_i:23+val_i:25+val_i:27+val_i:30+val_i:34+val_i:35+val_i:39+val_i:40+val_i:42+val_i:43+val_i:44+val_i:46+val_i:47+val_i:48+val_i:49+val_i:50+val_i:51+val_i:53+val_i:54+val_i:57+val_i:58+val_i:60+val_i:61+val_i:63+val_i:65&wt=xml} hits=36 status=0 QTime=6
[junit4] 2> 508663 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+}-_query_:"val_s:{00058+TO+00065]"&q={!+cost%3D60}id:RAND-1118765797+OR+val_s:[00058+TO+00089}&facet=true&wt=xml} hits=8 status=0 QTime=3
[junit4] 2> 508676 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}+-val_i:0+-val_i:1+-val_i:3+-val_i:7+-val_i:8+-val_i:11+-val_i:12+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:21+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:35+-val_i:36+-val_i:42+-val_i:43+-val_i:46+-val_i:48+-val_i:49+-val_i:50+-val_i:51+-val_i:52+-val_i:53+-val_i:54+-val_i:57+-val_i:60+-val_i:61+-val_i:62+-val_i:65&q={!+cost%3D169+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D65+u%3D69}"&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00012+TO+00066}"&fq=val_i:{00038+TO+00074]+OR+val_i:[00039+TO+00075}&fq={!frange+v%3Dval_i+l%3D56+u%3D80+cost%3D48+tag%3Dt}&facet=true&wt=xml} hits=0 status=0 QTime=11
[junit4] 2> 508698 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:6+-val_i:7+-val_i:9+-val_i:12+-val_i:14+-val_i:15+-val_i:16+-val_i:18+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:27+-val_i:29+-val_i:36+-val_i:38+-val_i:39+-val_i:41+-val_i:42+-val_i:45+-val_i:46+-val_i:47+-val_i:53+-val_i:55+-val_i:56+-val_i:57+-val_i:59+-val_i:60+-val_i:61+-val_i:62+-val_i:64&fq={!}id:RAND-1262661898+OR+val_s:[00004+TO+00063}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:{00051+TO+00065]"&fq={!+cache%3Dfalse+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D36+u%3D65}"&fq={!frange+v%3Dval_i+l%3D28+u%3D86}&wt=xml} hits=7 status=0 QTime=21
[junit4] 2> 508705 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D50+u%3D95}&q={!+cost%3D198}-_query_:"{!frange+v%3Dval_i+l%3D54+u%3D65}"&fq=-_query_:"val_i:[00041+TO+00066}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:3+-val_i:8+-val_i:9+-val_i:11+-val_i:14+-val_i:15+-val_i:16+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:24+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:35+-val_i:36+-val_i:38+-val_i:39+-val_i:40+-val_i:42+-val_i:44+-val_i:46+-val_i:49+-val_i:51+-val_i:53+-val_i:55+-val_i:58+-val_i:59+-val_i:64+-val_i:65&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00047+TO+00071]&fq={!+cache%3Dfalse+tag%3Dt}-_query_:"val_i:[00060+TO+00066}"&facet=true&wt=xml} hits=0 status=0 QTime=6
[junit4] 2> 508706 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D55+u%3D65}"&q={!frange+v%3Dval_i+l%3D15+u%3D60+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml} hits=46 status=0 QTime=0
[junit4] 2> 508722 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D40}-_query_:"{!frange+v%3Dval_i+l%3D59+u%3D65}"&q={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:9+-val_i:11+-val_i:14+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:26+-val_i:29+-val_i:30+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:39+-val_i:40+-val_i:41+-val_i:43+-val_i:44+-val_i:45+-val_i:46+-val_i:54+-val_i:56+-val_i:57+-val_i:58+-val_i:59+-val_i:61+-val_i:62+-val_i:63+-val_i:64&fq={!+cache%3Dfalse+tag%3Dt}val_i:[00061+TO+00066]&facet=true&wt=xml} hits=1 status=0 QTime=16
[junit4] 2> 508731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+tag%3Dt}+val_i:1+val_i:2+val_i:4+val_i:7+val_i:8+val_i:10+val_i:11+val_i:12+val_i:13+val_i:19+val_i:21+val_i:23+val_i:29+val_i:30+val_i:31+val_i:37+val_i:42+val_i:43+val_i:50+val_i:58+val_i:59+val_i:62+val_i:64&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00060+TO+00075]+OR+val_i:{00059+TO+00076}&fq={!cache%3Dtrue}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:4+val_i:5+val_i:6+val_i:7+val_i:13+val_i:14+val_i:15+val_i:16+val_i:19+val_i:20+val_i:21+val_i:22+val_i:23+val_i:24+val_i:28+val_i:30+val_i:31+val_i:32+val_i:33+val_i:34+val_i:35+val_i:38+val_i:39+val_i:45+val_i:46+val_i:48+val_i:49+val_i:50+val_i:52+val_i:53+val_i:56+val_i:59+val_i:64+val_i:65&fq=-_query_:"val_i:{00051+TO+00066}"&fq={!cache%3Dfalse+cost%3D197}+-val_i:0+-val_i:1+-val_i:4+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:14+-val_i:16+-val_i:23+-val_i:24+-val_i:25+-val_i:27+-val_i:30+-val_i:33+-val_i:35+-val_i:36+-val_i:38+-val_i:39+-val_i:40+-val_i:42+-val_i:45+-val_i:47+-val_i:48+-val_i:52+-val_i:54+-val_i:55+-val_i:56+-val_i:57+-val_i:58+-val_i:60+-val_i:63+-val_i:64&wt=xml} hits=0 status=0 QTime=8
[junit4] 2> 508742 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D61}+-val_i:1+-val_i:2+-val_i:6+-val_i:7+-val_i:8+-val_i:11+-val_i:14+-val_i:15+-val_i:17+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:25+-val_i:27+-val_i:29+-val_i:32+-val_i:33+-val_i:35+-val_i:38+-val_i:40+-val_i:44+-val_i:45+-val_i:46+-val_i:47+-val_i:49+-val_i:50+-val_i:52+-val_i:54+-val_i:56+-val_i:57+-val_i:58+-val_i:59+-val_i:62+-val_i:64&q={!cache%3Dfalse+cost%3D127+tag%3Dt}+val_i:0+val_i:2+val_i:3+val_i:4+val_i:8+val_i:12+val_i:14+val_i:15+val_i:16+val_i:19+val_i:20+val_i:21+val_i:23+val_i:25+val_i:28+val_i:29+val_i:30+val_i:34+val_i:38+val_i:39+val_i:41+val_i:42+val_i:43+val_i:45+val_i:46+val_i:48+val_i:49+val_i:50+val_i:51+val_i:52+val_i:53+val_i:54+val_i:56+val_i:59+val_i:61+val_i:63+val_i:64+val_i:65&fq={!cache%3Dfalse+tag%3Dt}+val_i:4+val_i:9+val_i:11+val_i:13+val_i:15+val_i:18+val_i:19+val_i:21+val_i:22+val_i:27+val_i:30+val_i:32+val_i:33+val_i:36+val_i:40+val_i:43+val_i:45+val_i:47+val_i:48+val_i:53+val_i:55+val_i:57+val_i:58+val_i:64+val_i:65&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:3+-val_i:5+-val_i:7+-val_i:10+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:19+-val_i:20+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:34+-val_i:35+-val_i:41+-val_i:47+-val_i:48+-val_i:50+-val_i:59+-val_i:60+-val_i:62+-val_i:65&fq={!+cache%3Dtrue+tag%3Dt}id:RAND2101259878+OR+val_i:[00043+TO+00102}&facet=true&wt=xml} hits=4 status=0 QTime=11
[junit4] 2> 508760 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse+tag%3Dt}-_query_:"val_i:{00038+TO+00065]"&fq={!+cache%3Dfalse+tag%3Dt}id:RAND1046029329+OR+val_i:{00032+TO+00067}&fq={!cache%3Dtrue+tag%3Dt}+val_i:3+val_i:5+val_i:6+val_i:7+val_i:10+val_i:11+val_i:12+val_i:14+val_i:17+val_i:18+val_i:19+val_i:21+val_i:23+val_i:25+val_i:28+val_i:29+val_i:30+val_i:31+val_i:32+val_i:33+val_i:35+val_i:36+val_i:40+val_i:42+val_i:48+val_i:49+val_i:50+val_i:51+val_i:54+val_i:55+val_i:58+val_i:59+val_i:60+val_i:61+val_i:63+val_i:64&wt=xml} hits=3 status=0 QTime=12
[junit4] 2> 508765 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D174}val_i:[00028+TO+00054}&q={!cache%3Dfalse+cost%3D93}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:6+-val_i:8+-val_i:9+-val_i:12+-val_i:13+-val_i:16+-val_i:19+-val_i:21+-val_i:23+-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:39+-val_i:42+-val_i:43+-val_i:46+-val_i:47+-val_i:48+-val_i:49+-val_i:51+-val_i:55+-val_i:56+-val_i:57+-val_i:59+-val_i:63+-val_i:64+-val_i:65&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00056+TO+00066}"&facet=true&wt=xml} hits=26 status=0 QTime=5
[junit4] 2> 508766 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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:{00062+TO+00094}&q={!+cache%3Dfalse}val_i:{00005+TO+00058}&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00065+TO+00065]+OR+id:RAND-1957900062&facet=true&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 508804 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+tag%3Dt}+val_i:1+val_i:2+val_i:3+val_i:4+val_i:5+val_i:6+val_i:9+val_i:13+val_i:14+val_i:17+val_i:19+val_i:20+val_i:21+val_i:24+val_i:26+val_i:27+val_i:29+val_i:32+val_i:34+val_i:35+val_i:36+val_i:38+val_i:41+val_i:42+val_i:43+val_i:44+val_i:45+val_i:46+val_i:49+val_i:51+val_i:52+val_i:53+val_i:56+val_i:62+val_i:63&group.main=true&group.cache.percent=100&fq={!frange+v%3Dval_i+l%3D40+u%3D99}&fq={!cache%3Dfalse+cost%3D196}+val_i:0+val_i:1+val_i:2+val_i:4+val_i:6+val_i:12+val_i:15+val_i:19+val_i:23+val_i:25+val_i:27+val_i:29+val_i:30+val_i:31+val_i:34+val_i:38+val_i:39+val_i:40+val_i:46+val_i:48+val_i:49+val_i:50+val_i:52+val_i:53+val_i:54+val_i:56+val_i:57+val_i:58+val_i:61+val_i:62+val_i:64&fq={!cache%3Dtrue}+val_i:0+val_i:3+val_i:5+val_i:6+val_i:8+val_i:10+val_i:11+val_i:13+val_i:18+val_i:20+val_i:21+val_i:22+val_i:24+val_i:25+val_i:27+val_i:28+val_i:30+val_i:32+val_i:36+val_i:38+val_i:39+val_i:40+val_i:42+val_i:45+val_i:51+val_i:52+val_i:53+val_i:56+val_i:59+val_i:60+val_i:61+val_i:62+val_i:63+val_i:65&wt=xml&group.field=id&group=true} hits=4 status=0 QTime=37
[junit4] 2> 508807 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse}val_i:{00005+TO+00051}&fq={!cache%3Dfalse+cost%3D16}+-val_i:1+-val_i:3+-val_i:5+-val_i:6+-val_i:7+-val_i:9+-val_i:10+-val_i:14+-val_i:17+-val_i:19+-val_i:24+-val_i:25+-val_i:29+-val_i:33+-val_i:36+-val_i:39+-val_i:40+-val_i:41+-val_i:44+-val_i:45+-val_i:49+-val_i:50+-val_i:51+-val_i:52+-val_i:54+-val_i:55+-val_i:56+-val_i:60+-val_i:61&fq={!+cache%3Dtrue+tag%3Dt}val_s:[00030+TO+00089}+AND+val_s:{00029+TO+00088]&wt=xml} hits=12 status=0 QTime=2
[junit4] 2> 508812 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+cost%3D152}+-val_i:2+-val_i:5+-val_i:7+-val_i:8+-val_i:9+-val_i:11+-val_i:14+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:27+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:40+-val_i:43+-val_i:44+-val_i:48+-val_i:49+-val_i:51+-val_i:53+-val_i:54+-val_i:56+-val_i:57+-val_i:58+-val_i:59+-val_i:60+-val_i:62+-val_i:64+-val_i:65&fq={!+cost%3D117}-_query_:"val_i:{00055+TO+00066}"&fq={!frange+v%3Dval_i+l%3D45+u%3D85+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dfalse+cost%3D120}+-val_i:2+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:24+-val_i:26+-val_i:29+-val_i:31+-val_i:36+-val_i:37+-val_i:39+-val_i:40+-val_i:43+-val_i:44+-val_i:45+-val_i:46+-val_i:48+-val_i:52+-val_i:53+-val_i:56+-val_i:58+-val_i:61+-val_i:62&wt=xml} hits=3 status=0 QTime=4
[junit4] 2> 508815 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!frange+v%3Dval_i+l%3D65+u%3D65}&group.main=true&fq={!cache%3Dtrue}+-val_i:0+-val_i:1+-val_i:4+-val_i:5+-val_i:8+-val_i:9+-val_i:11+-val_i:12+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:29+-val_i:30+-val_i:31+-val_i:34+-val_i:36+-val_i:37+-val_i:41+-val_i:42+-val_i:43+-val_i:44+-val_i:46+-val_i:47+-val_i:48+-val_i:50+-val_i:53+-val_i:54+-val_i:55+-val_i:58+-val_i:61&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00026+TO+00066}"&wt=xml&group.field=id&group=true} hits=0 status=0 QTime=3
[junit4] 2> 508817 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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:1+-val_i:2+-val_i:4+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:14+-val_i:20+-val_i:23+-val_i:24+-val_i:29+-val_i:31+-val_i:34+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:41+-val_i:45+-val_i:46+-val_i:47+-val_i:48+-val_i:49+-val_i:50+-val_i:51+-val_i:54+-val_i:57+-val_i:58+-val_i:59+-val_i:61+-val_i:62+-val_i:63+-val_i:65&q={!frange+v%3Dval_i+l%3D58+u%3D97+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml} hits=8 status=0 QTime=2
[junit4] 2> 508819 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cache%3Dfalse}-_query_:"{!frange+v%3Dval_i+l%3D56+u%3D65}"&q={!cache%3Dfalse+cost%3D59+tag%3Dt}+-val_i:2+-val_i:3+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:13+-val_i:15+-val_i:16+-val_i:17+-val_i:19+-val_i:23+-val_i:27+-val_i:30+-val_i:31+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:41+-val_i:46+-val_i:47+-val_i:52+-val_i:54+-val_i:60+-val_i:61+-val_i:62&facet=true&wt=xml} hits=36 status=0 QTime=1
[junit4] 2> 508823 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D184}-_query_:"val_i:{00063+TO+00067]"&q={!cache%3Dfalse}+val_i:0+val_i:1+val_i:2+val_i:4+val_i:5+val_i:8+val_i:10+val_i:12+val_i:14+val_i:16+val_i:17+val_i:19+val_i:21+val_i:22+val_i:23+val_i:25+val_i:26+val_i:27+val_i:31+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:44+val_i:45+val_i:47+val_i:50+val_i:53+val_i:54+val_i:60&fq={!cache%3Dfalse+cost%3D140}+val_i:5+val_i:7+val_i:8+val_i:10+val_i:12+val_i:18+val_i:20+val_i:21+val_i:23+val_i:25+val_i:26+val_i:27+val_i:28+val_i:32+val_i:33+val_i:36+val_i:37+val_i:38+val_i:43+val_i:44+val_i:46+val_i:47+val_i:48+val_i:51+val_i:52+val_i:53+val_i:54+val_i:55+val_i:57+val_i:58+val_i:59+val_i:64+val_i:65&fq={!}val_i:[00032+TO+00067}+OR+id:RAND-1908877858&fq=-_query_:"{!frange+v%3Dval_i+l%3D50+u%3D65}"&fq={!+cost%3D154}val_s:[00039+TO+00073}&facet=true&wt=xml} hits=2 status=0 QTime=3
[junit4] 2> 508828 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse}+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-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:23+-val_i:24+-val_i:26+-val_i:29+-val_i:30+-val_i:31+-val_i:33+-val_i:35+-val_i:36+-val_i:39+-val_i:40+-val_i:41+-val_i:44+-val_i:46+-val_i:50+-val_i:51+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:57+-val_i:61+-val_i:63+-val_i:65&fq=-_query_:"{!frange+v%3Dval_i+l%3D61+u%3D65}"&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D5+u%3D65}"&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00054+TO+00103}&fq={!cache%3Dfalse+cost%3D125+tag%3Dt}+-val_i:0+-val_i:3+-val_i:5+-val_i:6+-val_i:8+-val_i:11+-val_i:12+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:34+-val_i:38+-val_i:39+-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:50+-val_i:51+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:59+-val_i:61+-val_i:62+-val_i:64&wt=xml} hits=0 status=0 QTime=4
[junit4] 2> 508831 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D3}-_query_:"val_i:{00013+TO+00065]"&q=val_s:{00063+TO+00104}+AND+val_s:[00064+TO+00103]&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-val_i:7+-val_i:9+-val_i:10+-val_i:13+-val_i:15+-val_i:16+-val_i:17+-val_i:19+-val_i:24+-val_i:25+-val_i:29+-val_i:30+-val_i:31+-val_i:34+-val_i:42+-val_i:43+-val_i:46+-val_i:47+-val_i:50+-val_i:52+-val_i:53+-val_i:54+-val_i:56+-val_i:60+-val_i:63+-val_i:64+-val_i:65&fq=-_query_:"val_i:{00036+TO+00066}"&facet=true&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 508831 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D26+u%3D65}"&q={!+cache%3Dfalse+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D65+u%3D68}"&facet=true&wt=xml} hits=65 status=0 QTime=0
[junit4] 2> 508835 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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:0+val_i:2+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:12+val_i:13+val_i:16+val_i:17+val_i:20+val_i:23+val_i:24+val_i:27+val_i:28+val_i:30+val_i:31+val_i:33+val_i:34+val_i:38+val_i:39+val_i:40+val_i:42+val_i:46+val_i:49+val_i:50+val_i:53+val_i:54+val_i:55+val_i:56+val_i:58+val_i:60&q={!cache%3Dtrue}+val_i:2+val_i:7+val_i:8+val_i:9+val_i:11+val_i:14+val_i:15+val_i:16+val_i:18+val_i:19+val_i:20+val_i:22+val_i:25+val_i:28+val_i:30+val_i:32+val_i:34+val_i:35+val_i:39+val_i:40+val_i:41+val_i:42+val_i:45+val_i:46+val_i:50+val_i:51+val_i:57+val_i:60+val_i:64&fq=-_query_:"val_i:[00020+TO+00065]"&fq={!frange+v%3Dval_i+l%3D59+u%3D83+cache%3Dtrue+tag%3Dt}&fq=+val_i:1+val_i:2+val_i:3+val_i:4+val_i:7+val_i:8+val_i:13+val_i:14+val_i:15+val_i:17+val_i:20+val_i:22+val_i:24+val_i:27+val_i:32+val_i:36+val_i:37+val_i:38+val_i:39+val_i:42+val_i:43+val_i:44+val_i:45+val_i:46+val_i:47+val_i:48+val_i:49+val_i:51+val_i:53+val_i:54+val_i:55+val_i:56+val_i:57+val_i:58+val_i:62+val_i:64&facet=true&wt=xml} hits=0 status=0 QTime=3
[junit4] 2> 508840 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D22+u%3D53}&q={!cache%3Dfalse+cost%3D131}+val_i:1+val_i:3+val_i:5+val_i:6+val_i:9+val_i:12+val_i:14+val_i:16+val_i:18+val_i:19+val_i:20+val_i:21+val_i:22+val_i:25+val_i:27+val_i:30+val_i:31+val_i:36+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:47+val_i:49+val_i:50+val_i:52+val_i:55+val_i:57+val_i:59+val_i:62+val_i:65&fq={!+cost%3D14}-_query_:"val_i:[00052+TO+00066}"&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00050+TO+00065]"&fq={!+cost%3D142+tag%3Dt}val_i:[00012+TO+00040}&facet=true&wt=xml} hits=16 status=0 QTime=4
[junit4] 2> 508859 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+tag%3Dt}+val_i:4+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:23+val_i:25+val_i:26+val_i:28+val_i:30+val_i:31+val_i:34+val_i:36+val_i:37+val_i:38+val_i:39+val_i:41+val_i:42+val_i:43+val_i:45+val_i:48+val_i:50+val_i:51+val_i:55+val_i:57+val_i:60+val_i:61&group.main=true&fq={!+cache%3Dfalse}-_query_:"{!frange+v%3Dval_i+l%3D42+u%3D65}"&fq={!frange+v%3Dval_i+l%3D37+u%3D74+cost%3D158}&fq={!+cost%3D109+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D21+u%3D65}"&fq={!}-_query_:"val_i:[00044+TO+00066}"&wt=xml&group.field=id&group=true} hits=0 status=0 QTime=18
[junit4] 2> 508860 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dfalse+cost%3D104}-_query_:"val_i:{00003+TO+00065]"&fq={!cache%3Dfalse+cost%3D71+tag%3Dt}+val_i:0+val_i:2+val_i:3+val_i:4+val_i:7+val_i:9+val_i:10+val_i:11+val_i:12+val_i:13+val_i:14+val_i:16+val_i:17+val_i:19+val_i:20+val_i:25+val_i:27+val_i:29+val_i:33+val_i:34+val_i:35+val_i:37+val_i:38+val_i:43+val_i:44+val_i:48+val_i:49+val_i:51+val_i:52+val_i:53+val_i:54+val_i:57+val_i:60+val_i:61+val_i:63+val_i:65&fq={!+cache%3Dfalse}-_query_:"{!frange+v%3Dval_i+l%3D45+u%3D65}"&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 508863 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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:{00005+TO+00046}&q={!cache%3Dfalse+cost%3D185}+-val_i:0+-val_i:4+-val_i:5+-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:20+-val_i:29+-val_i:33+-val_i:35+-val_i:36+-val_i:40+-val_i:41+-val_i:42+-val_i:45+-val_i:49+-val_i:50+-val_i:52+-val_i:53+-val_i:59+-val_i:62+-val_i:63+-val_i:64&fq={!frange+v%3Dval_i+l%3D51+u%3D75}&fq={!cache%3Dfalse+cost%3D156}+val_i:0+val_i:2+val_i:3+val_i:4+val_i:10+val_i:18+val_i:21+val_i:22+val_i:23+val_i:25+val_i:31+val_i:33+val_i:34+val_i:35+val_i:43+val_i:48+val_i:49+val_i:53+val_i:54+val_i:55+val_i:56+val_i:58+val_i:59+val_i:61+val_i:63+val_i:64+val_i:65&fq=val_i:{00007+TO+00056]&facet=true&wt=xml} hits=3 status=0 QTime=2
[junit4] 2> 508867 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D9}+val_i:0+val_i:4+val_i:5+val_i:6+val_i:7+val_i:10+val_i:12+val_i:15+val_i:24+val_i:25+val_i:27+val_i:29+val_i:32+val_i:34+val_i:35+val_i:38+val_i:39+val_i:42+val_i:43+val_i:44+val_i:45+val_i:48+val_i:50+val_i:53+val_i:54+val_i:56+val_i:57+val_i:60+val_i:62+val_i:63+val_i:64+val_i:65&q={!+cost%3D151}-_query_:"val_i:[00004+TO+00065]"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:3+-val_i:4+-val_i:6+-val_i:7+-val_i:8+-val_i:9+-val_i:11+-val_i:12+-val_i:13+-val_i:21+-val_i:23+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:36+-val_i:39+-val_i:40+-val_i:42+-val_i:44+-val_i:47+-val_i:52+-val_i:53+-val_i:54+-val_i:57+-val_i:58+-val_i:59+-val_i:60+-val_i:61+-val_i:63+-val_i:64&fq={!cache%3Dfalse+cost%3D170+tag%3Dt}+val_i:0+val_i:4+val_i:6+val_i:8+val_i:11+val_i:13+val_i:17+val_i:18+val_i:19+val_i:21+val_i:22+val_i:27+val_i:29+val_i:33+val_i:35+val_i:37+val_i:41+val_i:43+val_i:44+val_i:46+val_i:47+val_i:48+val_i:49+val_i:51+val_i:55+val_i:56+val_i:57+val_i:60+val_i:61+val_i:62&facet=true&wt=xml} hits=1 status=0 QTime=4
[junit4] 2> 508880 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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%3D110}+val_i:0+val_i:3+val_i:7+val_i:11+val_i:12+val_i:13+val_i:16+val_i:19+val_i:23+val_i:24+val_i:25+val_i:27+val_i:28+val_i:29+val_i:32+val_i:34+val_i:36+val_i:37+val_i:38+val_i:41+val_i:46+val_i:49+val_i:51+val_i:53+val_i:54+val_i:58+val_i:59+val_i:60+val_i:61+val_i:62+val_i:64&q={!cache%3Dfalse}+-val_i:3+-val_i:4+-val_i:8+-val_i:12+-val_i:13+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:28+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:41+-val_i:42+-val_i:44+-val_i:45+-val_i:49+-val_i:50+-val_i:53+-val_i:58+-val_i:59+-val_i:61+-val_i:62+-val_i:63+-val_i:64+-val_i:65&fq={!cache%3Dfalse+cost%3D170}+-val_i:1+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-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:20+-val_i:21+-val_i:22+-val_i:24+-val_i:26+-val_i:30+-val_i:31+-val_i:32+-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:46+-val_i:47+-val_i:48+-val_i:52+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:58+-val_i:63+-val_i:64&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00016+TO+00065]"&fq={!+cache%3Dfalse+cost%3D146+tag%3Dt}-_query_:"val_i:{00048+TO+00066}"&fq={!cache%3Dfalse}+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:9+-val_i:12+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:30+-val_i:32+-val_i:34+-val_i:36+-val_i:37+-val_i:40+-val_i:41+-val_i:43+-val_i:46+-val_i:49+-val_i:51+-val_i:52+-val_i:54+-val_i:55+-val_i:57+-val_i:59+-val_i:60+-val_i:61+-val_i:62+-val_i:63+-val_i:64+-val_i:65&facet=true&wt=xml} hits=3 status=0 QTime=13
[junit4] 2> 508883 INFO (TEST-TestFiltering.testRandomFiltering-seed#[9439114A4578DFFC]) [ ] 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:4+val_i:5+val_i:10+val_i:11+val_i:13+val_i:15+val_i:16+val_i:17+val_i:18+val_i:20+val_i:21+val_i:22+val_i:23+val_i:25+val_i:27+val_i:28+val_i:30+val_i:31+val_i:32+val_i:34+val_i:36+val_i:37+val_i:38+val_i:39+val_i:41+val_i:42+val_i:43+val_i:45+val_i:47+val_i:50+val_i:52+val_i:57+val_i:58+val_i:60+val_i:62+val_i:63&q={!+
[...truncated too long message...]
2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4) Thread[id=9117, name=Thread-1197, state=WAITING, group=TGRP-TestFiltering]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:2295)
[junit4] 2> 5) Thread[id=9115, name=h2sc-6382-thread-2, state=RUNNABLE, group=TGRP-TestFiltering]
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] 2> at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
[junit4] 2> at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$$Lambda$65/1783877629.run(Unknown Source)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$66/1444852501.run(Unknown Source)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 6) Thread[id=9119, name=searcherExecutor-6387-thread-1, state=WAITING, group=TGRP-TestFiltering]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 7) Thread[id=9114, name=h2sc-6382-thread-1, state=RUNNABLE, group=TGRP-TestFiltering]
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] 2> at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
[junit4] 2> at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$$Lambda$65/1783877629.run(Unknown Source)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$66/1444852501.run(Unknown Source)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=es-CO, timezone=Africa/El_Aaiun
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=10,free=297444200,total=533725184
[junit4] 2> NOTE: All tests run in this JVM: [TestXmlQParser, TestDocTermOrdsUninvertLimit, ReindexCollectionTest, CollectionsAPISolrJTest, ZookeeperStatusHandlerFailureTest, TestSolrQueryResponse, RestoreTriggerStateTest, TriggerCooldownIntegrationTest, TestLegacyNumericRangeQueryBuilder, TestPostingsSolrHighlighter, TestSchemaDesignerConfigSetHelper, HdfsAutoAddReplicasIntegrationTest, TestSolrCloudWithDelegationTokens, TestCloudPseudoReturnFields, DistanceFunctionTest, TestTrackingShardHandlerFactory, DaemonStreamApiTest, SolrCoreTest, BasicFunctionalityTest, CdcrBidirectionalTest, TestComponentsName, TestExtendedDismaxParser, CustomHighlightComponentTest, RoutingToNodesWithPropertiesTest, LocalFSCloudIncrementalBackupTest, PreAnalyzedFieldTest, UnloadDistributedZkTest, SplitByPrefixTest, CoreAdminHandlerTest, AnalysisErrorHandlingTest, TestBlendedInfixSuggestions, TestFiltering]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestFiltering -Dtests.seed=9439114A4578DFFC -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-CO -Dtests.timezone=Africa/El_Aaiun -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestFiltering (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([9439114A4578DFFC]:0)
[junit4] Completed [870/955 (1!)] on J1 in 7212.70s, 4 tests, 2 errors <<< FAILURES!
[...truncated 54511 lines...]