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/10/05 22:29:08 UTC
[JENKINS] Lucene » Lucene-Solr-NightlyTests-8.x - Build # 412 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.x/412/
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([941028414E3EFF6C]: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([941028414E3EFF6C]:0)
Build Log:
[...truncated 16555 lines...]
[junit4] Suite: org.apache.solr.search.TestFiltering
[junit4] 2> 4376848 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-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-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 4376849 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestFiltering_941028414E3EFF6C-001/data-dir-178-001
[junit4] 2> 4376849 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 4376849 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 4376851 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-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> 4376851 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 4376851 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 4376858 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4376875 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 4376875 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 4376875 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 4376878 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 4376881 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 4377178 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_291]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_291]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_291]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_291]
[junit4] 2> at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) ~[?:?]
[junit4] 2> at sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376) ~[?:?]
[junit4] 2> at java.nio.file.Files.createDirectory(Files.java:674) ~[?:1.8.0_291]
[junit4] 2> at java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_291]
[junit4] 2> at java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_291]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:378) [java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:343) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:183) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:813) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:803) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:664) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:653) [java/:?]
[junit4] 2> at org.apache.solr.search.TestFiltering.beforeTests(TestFiltering.java:45) [test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_291]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_291]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> 4377180 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_291]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_291]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_291]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_291]
[junit4] 2> at java.io.File.mkdir(File.java:1348) ~[?:1.8.0_291]
[junit4] 2> at java.io.File.mkdirs(File.java:1380) ~[?:1.8.0_291]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561) [java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81) [java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) [java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:679) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:184) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:813) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:803) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:664) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:653) [java/:?]
[junit4] 2> at org.apache.solr.search.TestFiltering.beforeTests(TestFiltering.java:45) [test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_291]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_291]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> 4377181 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4377181 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-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> 4377182 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4522196b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4377182 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4522196b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4377186 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-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> 4377190 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6d9918c6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4377190 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6d9918c6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4377191 WARN (SUITE-TestFiltering-seed#[941028414E3EFF6C]-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> 4377223 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e6ca2
[junit4] 2> 4377230 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e6ca2
[junit4] 2> 4377230 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58e6ca2
[junit4] 2> 4377232 INFO (coreLoadExecutor-15995-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 4377241 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4377267 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 4377267 WARN (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 4377268 WARN (coreLoadExecutor-15995-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> 4377270 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 4377273 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 4377281 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 4377282 INFO (coreLoadExecutor-15995-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@58e6ca2
[junit4] 2> 4377282 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestFiltering_941028414E3EFF6C-001/data-dir-178-001/]
[junit4] 2> 4377283 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=828494060, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 4377342 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4377342 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4377343 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=5, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.3759765625, noCFSRatio=0.3805283097344102]
[junit4] 2> 4377345 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 4377448 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 4377540 INFO (coreLoadExecutor-15995-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 4377540 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 4377541 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 4377541 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 4377541 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 4377541 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 4377541 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 4377541 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 4377543 INFO (SUITE-TestFiltering-seed#[941028414E3EFF6C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 4377547 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 4377547 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 4377548 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377555 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHossssSanity
[junit4] 2> 4377555 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4377556 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] 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> 4377557 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 4377559 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 4377560 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 4377560 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 4377560 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 4377561 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 4377561 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 4377561 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 4377561 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 4377562 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 4377580 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377581 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 4377581 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=10 status=0 QTime=0
[junit4] 2> 4377582 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] 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> 4377583 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] 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> 4377583 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 4377584 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] 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> 4377584 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] 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> 4377585 INFO (TEST-TestFiltering.testHossssSanity-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHossssSanity
[junit4] 2> 4377591 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLiveDocsSharing
[junit4] 2> 4377591 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4377591 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ ] 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> 4377592 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 4377593 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 4377593 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 4377593 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 4377593 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 4377594 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 4377594 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 4377594 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 4377594 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 4377595 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 4377595 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 4377595 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 4377595 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 4377596 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 4377596 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 0
[junit4] 2> 4377596 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 0
[junit4] 2> 4377596 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 0
[junit4] 2> 4377597 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 0
[junit4] 2> 4377597 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 4377597 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 4377597 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 0
[junit4] 2> 4377598 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17]} 0 0
[junit4] 2> 4377602 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[18]} 0 3
[junit4] 2> 4377602 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[19]} 0 0
[junit4] 2> 4377614 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377615 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 12
[junit4] 2> 4377622 INFO (TEST-TestFiltering.testLiveDocsSharing-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLiveDocsSharing
[junit4] 2> 4377628 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCaching
[junit4] 2> 4377629 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4377629 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377630 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 4377630 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 4377630 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 4377630 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 4377643 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377643 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 12
[junit4] 2> 4377645 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377645 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377645 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377646 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377646 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377646 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377647 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D6+cache%3Dfalse}val_i&facet=true&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 4377648 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D6+cache%3Dfalse}val_i&facet=true&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 4377648 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377648 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377649 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377649 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] 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> 4377649 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse}*:*&fl=val_i&start=2&sort=val_i+asc&rows=1&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 4377650 INFO (TEST-TestFiltering.testCaching-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCaching
[junit4] 2> 4377657 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomFiltering
[junit4] 2> 4377657 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4377657 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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> 4377658 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[0]} 0 0
[junit4] 2> 4377658 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[0]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[1]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[2]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[3]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[4]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[4]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[5]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[6]} 0 0
[junit4] 2> 4377659 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[7]} 0 0
[junit4] 2> 4377660 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[8]} 0 0
[junit4] 2> 4377660 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[9]} 0 0
[junit4] 2> 4377660 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[9]} 0 0
[junit4] 2> 4377677 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377678 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 17
[junit4] 2> 4377679 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[10]} 0 0
[junit4] 2> 4377679 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[10]} 0 0
[junit4] 2> 4377691 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377691 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 12
[junit4] 2> 4377692 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[11]} 0 0
[junit4] 2> 4377692 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[12]} 0 0
[junit4] 2> 4377692 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[13]} 0 0
[junit4] 2> 4377692 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[14]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[15]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[15]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[16]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[17]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[18]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[19]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[20]} 0 0
[junit4] 2> 4377693 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[21]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[22]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[23]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[24]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[24]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[25]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[26]} 0 0
[junit4] 2> 4377694 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[27]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[28]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[29]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[30]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[31]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[32]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[33]} 0 0
[junit4] 2> 4377695 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[33]} 0 0
[junit4] 2> 4377707 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377707 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 11
[junit4] 2> 4377708 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[34]} 0 0
[junit4] 2> 4377708 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[35]} 0 0
[junit4] 2> 4377709 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[36]} 0 0
[junit4] 2> 4377709 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[36]} 0 0
[junit4] 2> 4377709 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[37]} 0 0
[junit4] 2> 4377709 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[38]} 0 0
[junit4] 2> 4377709 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[39]} 0 0
[junit4] 2> 4377727 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 1 ms
[junit4] 2> 4377728 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 4377729 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[40]} 0 0
[junit4] 2> 4377729 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[41]} 0 0
[junit4] 2> 4377729 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[42]} 0 0
[junit4] 2> 4377729 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[43]} 0 0
[junit4] 2> 4377729 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[43]} 0 0
[junit4] 2> 4377729 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[44]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[45]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[46]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[47]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[48]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[49]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[50]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[51]} 0 0
[junit4] 2> 4377730 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[51]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[52]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[53]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[54]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[55]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[56]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[57]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[58]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[59]} 0 0
[junit4] 2> 4377731 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[59]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[60]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[61]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[62]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[62]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[63]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[64]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[65]} 0 0
[junit4] 2> 4377732 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[65]} 0 0
[junit4] 2> 4377751 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377751 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 4377752 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[66]} 0 0
[junit4] 2> 4377752 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[67]} 0 0
[junit4] 2> 4377753 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[68]} 0 0
[junit4] 2> 4377753 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[69]} 0 0
[junit4] 2> 4377753 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[70]} 0 0
[junit4] 2> 4377753 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[71]} 0 0
[junit4] 2> 4377753 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[72]} 0 0
[junit4] 2> 4377753 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[73]} 0 0
[junit4] 2> 4377766 INFO (searcherExecutor-15997-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4377766 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 4377767 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=74 status=0 QTime=0
[junit4] 2> 4377769 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D12+u%3D73}"&fq={!cache%3Dtrue}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:4+val_i:6+val_i:13+val_i:15+val_i:20+val_i:21+val_i:22+val_i:23+val_i:27+val_i:28+val_i:31+val_i:32+val_i:33+val_i:34+val_i:38+val_i:43+val_i:47+val_i:50+val_i:51+val_i:53+val_i:57+val_i:58+val_i:61+val_i:63+val_i:65+val_i:67+val_i:68+val_i:69+val_i:71+val_i:72+val_i:73&fq={!}val_s:{00055+TO+00126]&fq={!frange+v%3Dval_i+l%3D9+u%3D30}&fq={!+cost%3D193}-_query_:"{!frange+v%3Dval_i+l%3D48+u%3D73}"&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 4377770 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!+cache%3Dtrue+tag%3Dt}val_i:[00027+TO+00074]&fq={!+cost%3D123+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D10+u%3D73}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D67+u%3D73}"&fq={!frange+v%3Dval_i+l%3D47+u%3D86}&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 4377772 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}+-val_i:2+-val_i:3+-val_i:6+-val_i:9+-val_i:10+-val_i:11+-val_i:16+-val_i:18+-val_i:20+-val_i:21+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:31+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:39+-val_i:41+-val_i:42+-val_i:43+-val_i:44+-val_i:45+-val_i:47+-val_i:48+-val_i:50+-val_i:52+-val_i:57+-val_i:58+-val_i:61+-val_i:62+-val_i:63+-val_i:69+-val_i:70+-val_i:71+-val_i:72&q={!frange+v%3Dval_i+l%3D48+u%3D76+cache%3Dfalse}&fq={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:2+val_i:3+val_i:4+val_i:5+val_i:6+val_i:7+val_i:11+val_i:16+val_i:18+val_i:22+val_i:26+val_i:27+val_i:29+val_i:30+val_i:31+val_i:32+val_i:34+val_i:35+val_i:36+val_i:39+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:54+val_i:55+val_i:56+val_i:58+val_i:59+val_i:64+val_i:66+val_i:67+val_i:69+val_i:70+val_i:71+val_i:73&facet=true&wt=xml} hits=16 status=0 QTime=2
[junit4] 2> 4377776 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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:3+val_i:5+val_i:8+val_i:9+val_i:12+val_i:13+val_i:19+val_i:21+val_i:26+val_i:27+val_i:28+val_i:30+val_i:31+val_i:32+val_i:34+val_i:36+val_i:38+val_i:42+val_i:43+val_i:45+val_i:46+val_i:47+val_i:51+val_i:53+val_i:54+val_i:56+val_i:57+val_i:61+val_i:62+val_i:66+val_i:67+val_i:68+val_i:70+val_i:72&fq={!cache%3Dfalse+cost%3D17+tag%3Dt}+-val_i:2+-val_i:4+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:13+-val_i:16+-val_i:17+-val_i:19+-val_i:22+-val_i:24+-val_i:25+-val_i:28+-val_i:29+-val_i:30+-val_i:33+-val_i:34+-val_i:35+-val_i:39+-val_i:40+-val_i:46+-val_i:49+-val_i:50+-val_i:52+-val_i:55+-val_i:57+-val_i:61+-val_i:62+-val_i:64+-val_i:65+-val_i:66+-val_i:67+-val_i:70+-val_i:72&fq={!frange+v%3Dval_i+l%3D12+u%3D50+tag%3Dt}&fq={!cache%3Dtrue}+val_i:0+val_i:2+val_i:3+val_i:5+val_i:7+val_i:8+val_i:11+val_i:13+val_i:14+val_i:18+val_i:19+val_i:22+val_i:26+val_i:27+val_i:31+val_i:33+val_i:34+val_i:38+val_i:39+val_i:40+val_i:44+val_i:47+val_i:49+val_i:51+val_i:52+val_i:53+val_i:55+val_i:58+val_i:61+val_i:62+val_i:63+val_i:68+val_i:70+val_i:71+val_i:73&fq={!}-_query_:"val_s:[00030+TO+00074}"&wt=xml} hits=2 status=0 QTime=3
[junit4] 2> 4377779 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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%3D192}-_query_:"val_i:[00026+TO+00074}"&q={!+cache%3Dtrue+tag%3Dt}val_i:{00005+TO+00079]+OR+val_i:[00006+TO+00080}&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:5+-val_i:7+-val_i:8+-val_i:9+-val_i:18+-val_i:19+-val_i:21+-val_i:23+-val_i:25+-val_i:26+-val_i:28+-val_i:32+-val_i:33+-val_i:38+-val_i:40+-val_i:41+-val_i:42+-val_i:43+-val_i:46+-val_i:47+-val_i:48+-val_i:49+-val_i:50+-val_i:51+-val_i:52+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:58+-val_i:62+-val_i:66+-val_i:67+-val_i:68+-val_i:72+-val_i:73&fq={!+cost%3D178}-_query_:"{!frange+v%3Dval_i+l%3D15+u%3D73}"&fq={!+cache%3Dfalse}-_query_:"{!frange+v%3Dval_i+l%3D72+u%3D78}"&fq={!cache%3Dfalse+cost%3D175}+val_i:0+val_i:1+val_i:2+val_i:4+val_i:7+val_i:9+val_i:12+val_i:13+val_i:14+val_i:15+val_i:18+val_i:21+val_i:23+val_i:24+val_i:26+val_i:28+val_i:32+val_i:35+val_i:38+val_i:41+val_i:42+val_i:44+val_i:46+val_i:47+val_i:49+val_i:50+val_i:52+val_i:53+val_i:54+val_i:55+val_i:61+val_i:62+val_i:64+val_i:65+val_i:67+val_i:68+val_i:71&facet=true&wt=xml} hits=3 status=0 QTime=2
[junit4] 2> 4377781 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+cost%3D57}+val_i:0+val_i:1+val_i:3+val_i:5+val_i:6+val_i:7+val_i:10+val_i:11+val_i:14+val_i:17+val_i:20+val_i:22+val_i:24+val_i:25+val_i:30+val_i:33+val_i:34+val_i:36+val_i:37+val_i:39+val_i:40+val_i:41+val_i:42+val_i:46+val_i:52+val_i:53+val_i:54+val_i:55+val_i:56+val_i:57+val_i:59+val_i:60+val_i:61+val_i:62+val_i:63+val_i:64+val_i:65+val_i:66+val_i:70+val_i:71+val_i:72+val_i:73&fq={!cache%3Dfalse+cost%3D102+tag%3Dt}+val_i:1+val_i:2+val_i:5+val_i:6+val_i:11+val_i:13+val_i:14+val_i:15+val_i:20+val_i:23+val_i:24+val_i:27+val_i:28+val_i:29+val_i:32+val_i:33+val_i:36+val_i:37+val_i:38+val_i:40+val_i:42+val_i:43+val_i:45+val_i:46+val_i:48+val_i:51+val_i:52+val_i:54+val_i:55+val_i:56+val_i:59+val_i:61+val_i:62+val_i:65+val_i:66+val_i:69+val_i:70+val_i:71+val_i:72&wt=xml} hits=25 status=0 QTime=1
[junit4] 2> 4377782 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+cost%3D84+tag%3Dt}+-val_i:2+-val_i:3+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:11+-val_i:13+-val_i:15+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:34+-val_i:37+-val_i:39+-val_i:42+-val_i:43+-val_i:47+-val_i:50+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:57+-val_i:59+-val_i:62+-val_i:64+-val_i:65+-val_i:67+-val_i:68+-val_i:70&wt=xml} hits=35 status=0 QTime=1
[junit4] 2> 4377791 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:2+val_i:13+val_i:14+val_i:15+val_i:16+val_i:17+val_i:21+val_i:22+val_i:24+val_i:27+val_i:28+val_i:30+val_i:31+val_i:32+val_i:36+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:47+val_i:48+val_i:49+val_i:50+val_i:54+val_i:55+val_i:56+val_i:58+val_i:59+val_i:61+val_i:62+val_i:63+val_i:66+val_i:69+val_i:70+val_i:71&fq=-_query_:"{!frange+v%3Dval_i+l%3D5+u%3D73}"&fq={!+tag%3Dt}val_i:[00073+TO+00074}+AND+val_i:{00072+TO+00073]&fq={!cache%3Dtrue+tag%3Dt}+val_i:3+val_i:10+val_i:11+val_i:13+val_i:15+val_i:17+val_i:19+val_i:22+val_i:26+val_i:31+val_i:34+val_i:35+val_i:39+val_i:41+val_i:42+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:58+val_i:59+val_i:61+val_i:62+val_i:64+val_i:66+val_i:67+val_i:68+val_i:70+val_i:73&fq={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:4+val_i:7+val_i:8+val_i:9+val_i:10+val_i:18+val_i:20+val_i:23+val_i:24+val_i:30+val_i:31+val_i:33+val_i:36+val_i:39+val_i:40+val_i:43+val_i:44+val_i:45+val_i:47+val_i:50+val_i:52+val_i:53+val_i:56+val_i:57+val_i:59+val_i:60+val_i:64+val_i:68+val_i:72&wt=xml} hits=0 status=0 QTime=8
[junit4] 2> 4377804 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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%3D20+u%3D73}"&q={!frange+v%3Dval_i+l%3D5+u%3D27}&group.main=true&group.cache.percent=100&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:3+val_i:5+val_i:6+val_i:8+val_i:9+val_i:10+val_i:11+val_i:16+val_i:17+val_i:21+val_i:23+val_i:24+val_i:27+val_i:28+val_i:36+val_i:37+val_i:38+val_i:39+val_i:40+val_i:42+val_i:45+val_i:46+val_i:47+val_i:50+val_i:51+val_i:52+val_i:56+val_i:57+val_i:59+val_i:60+val_i:62+val_i:63+val_i:65+val_i:66+val_i:67+val_i:68+val_i:69+val_i:70+val_i:73&fq={!+tag%3Dt}-_query_:"val_i:[00023+TO+00073]"&fq={!cache%3Dfalse}+val_i:1+val_i:2+val_i:3+val_i:4+val_i:7+val_i:12+val_i:13+val_i:15+val_i:16+val_i:21+val_i:23+val_i:27+val_i:33+val_i:34+val_i:37+val_i:38+val_i:39+val_i:41+val_i:42+val_i:44+val_i:45+val_i:46+val_i:47+val_i:51+val_i:53+val_i:55+val_i:58+val_i:59+val_i:60+val_i:61+val_i:64+val_i:66+val_i:67+val_i:68+val_i:69+val_i:71&fq={!cache%3Dfalse}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:4+val_i:5+val_i:8+val_i:9+val_i:14+val_i:15+val_i:16+val_i:17+val_i:18+val_i:19+val_i:21+val_i:22+val_i:25+val_i:26+val_i:27+val_i:30+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:46+val_i:47+val_i:50+val_i:51+val_i:52+val_i:53+val_i:54+val_i:57+val_i:58+val_i:59+val_i:60+val_i:63+val_i:64+val_i:65+val_i:66+val_i:67+val_i:71&facet=true&wt=xml&group.field=id&group=true} hits=2 status=0 QTime=13
[junit4] 2> 4377806 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue}+val_i:3+val_i:4+val_i:8+val_i:9+val_i:11+val_i:12+val_i:13+val_i:14+val_i:16+val_i:17+val_i:18+val_i:19+val_i:22+val_i:23+val_i:25+val_i:26+val_i:27+val_i:28+val_i:31+val_i:32+val_i:34+val_i:35+val_i:36+val_i:38+val_i:39+val_i:40+val_i:44+val_i:45+val_i:47+val_i:49+val_i:54+val_i:55+val_i:56+val_i:59+val_i:61+val_i:67+val_i:68+val_i:70+val_i:73&fq={!+cost%3D113}-_query_:"{!frange+v%3Dval_i+l%3D23+u%3D73}"&fq={!frange+v%3Dval_i+l%3D38+u%3D84+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dfalse+cost%3D36+tag%3Dt}+val_i:2+val_i:4+val_i:5+val_i:8+val_i:9+val_i:10+val_i:12+val_i:15+val_i:16+val_i:17+val_i:18+val_i:19+val_i:20+val_i:21+val_i:23+val_i:27+val_i:29+val_i:32+val_i:35+val_i:37+val_i:40+val_i:43+val_i:44+val_i:46+val_i:54+val_i:55+val_i:56+val_i:59+val_i:64+val_i:66+val_i:67+val_i:68+val_i:70+val_i:72&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 4377808 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!frange+v%3Dval_i+l%3D39+u%3D102+cache%3Dfalse+tag%3Dt}&fq={!cache%3Dtrue}+val_i:2+val_i:3+val_i:5+val_i:7+val_i:8+val_i:11+val_i:12+val_i:14+val_i:16+val_i:18+val_i:23+val_i:24+val_i:26+val_i:28+val_i:31+val_i:32+val_i:34+val_i:36+val_i:38+val_i:39+val_i:42+val_i:45+val_i:48+val_i:50+val_i:55+val_i:56+val_i:57+val_i:58+val_i:59+val_i:62+val_i:63+val_i:64+val_i:65+val_i:66+val_i:69+val_i:70+val_i:71&fq={!+cache%3Dfalse+tag%3Dt}val_i:[00024+TO+00091]&fq=-_query_:"{!frange+v%3Dval_i+l%3D23+u%3D73}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:3+-val_i:4+-val_i:5+-val_i:12+-val_i:17+-val_i:18+-val_i:21+-val_i:22+-val_i:23+-val_i:28+-val_i:29+-val_i:31+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:39+-val_i:40+-val_i:42+-val_i:44+-val_i:49+-val_i:51+-val_i:57+-val_i:60+-val_i:61+-val_i:64+-val_i:65+-val_i:66+-val_i:67+-val_i:68+-val_i:70+-val_i:71+-val_i:73&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 4377810 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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%3D63+u%3D84+cost%3D134}&q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:16+-val_i:19+-val_i:21+-val_i:23+-val_i:24+-val_i:26+-val_i:28+-val_i:30+-val_i:31+-val_i:34+-val_i:35+-val_i:38+-val_i:39+-val_i:42+-val_i:44+-val_i:45+-val_i:46+-val_i:47+-val_i:49+-val_i:50+-val_i:51+-val_i:54+-val_i:55+-val_i:60+-val_i:61+-val_i:64+-val_i:67+-val_i:68+-val_i:69+-val_i:70+-val_i:72&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:5+val_i:6+val_i:9+val_i:11+val_i:12+val_i:14+val_i:19+val_i:20+val_i:21+val_i:23+val_i:24+val_i:27+val_i:28+val_i:30+val_i:33+val_i:37+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:50+val_i:51+val_i:53+val_i:54+val_i:55+val_i:56+val_i:57+val_i:64+val_i:66+val_i:67+val_i:70+val_i:72&fq=+val_i:0+val_i:4+val_i:5+val_i:9+val_i:11+val_i:16+val_i:17+val_i:21+val_i:22+val_i:23+val_i:25+val_i:26+val_i:27+val_i:28+val_i:29+val_i:32+val_i:36+val_i:38+val_i:41+val_i:44+val_i:45+val_i:46+val_i:47+val_i:48+val_i:49+val_i:54+val_i:55+val_i:57+val_i:58+val_i:60+val_i:61+val_i:64+val_i:69+val_i:70+val_i:71+val_i:72+val_i:73&fq={!frange+v%3Dval_i+l%3D22+u%3D60+cache%3Dfalse+cost%3D121}&fq={!cache%3Dfalse+cost%3D109}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:4+val_i:6+val_i:9+val_i:10+val_i:11+val_i:12+val_i:13+val_i:16+val_i:18+val_i:19+val_i:22+val_i:24+val_i:26+val_i:28+val_i:30+val_i:34+val_i:36+val_i:37+val_i:38+val_i:44+val_i:45+val_i:46+val_i:49+val_i:50+val_i:52+val_i:53+val_i:54+val_i:56+val_i:57+val_i:60+val_i:61+val_i:69+val_i:70+val_i:72+val_i:73&facet=true&wt=xml} hits=1 status=0 QTime=2
[junit4] 2> 4377813 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse}+-val_i:0+-val_i:2+-val_i:3+-val_i:4+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:14+-val_i:15+-val_i:16+-val_i:19+-val_i:23+-val_i:27+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:38+-val_i:40+-val_i:41+-val_i:42+-val_i:43+-val_i:44+-val_i:47+-val_i:49+-val_i:50+-val_i:51+-val_i:52+-val_i:53+-val_i:54+-val_i:55+-val_i:57+-val_i:63+-val_i:64+-val_i:66+-val_i:67+-val_i:69+-val_i:72&fq={!cache%3Dfalse+tag%3Dt}+val_i:1+val_i:2+val_i:3+val_i:5+val_i:11+val_i:12+val_i:13+val_i:14+val_i:15+val_i:17+val_i:18+val_i:20+val_i:21+val_i:22+val_i:27+val_i:29+val_i:31+val_i:32+val_i:36+val_i:38+val_i:39+val_i:41+val_i:49+val_i:50+val_i:56+val_i:57+val_i:58+val_i:59+val_i:60+val_i:63+val_i:64+val_i:65+val_i:66+val_i:67+val_i:68+val_i:69+val_i:71+val_i:72&fq={!cache%3Dfalse+cost%3D192}+val_i:2+val_i:3+val_i:5+val_i:6+val_i:10+val_i:11+val_i:12+val_i:13+val_i:15+val_i:17+val_i:18+val_i:19+val_i:22+val_i:29+val_i:30+val_i:35+val_i:36+val_i:37+val_i:40+val_i:42+val_i:43+val_i:44+val_i:46+val_i:47+val_i:49+val_i:54+val_i:55+val_i:57+val_i:60+val_i:62+val_i:63+val_i:64+val_i:65+val_i:66+val_i:67+val_i:70+val_i:73&fq={!+cache%3Dfalse}val_i:{00007+TO+00078}+OR+val_i:[00008+TO+00077]&wt=xml} hits=8 status=0 QTime=1
[junit4] 2> 4377815 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue+tag%3Dt}+-val_i:2+-val_i:4+-val_i:6+-val_i:7+-val_i:8+-val_i:11+-val_i:14+-val_i:15+-val_i:16+-val_i:18+-val_i:21+-val_i:22+-val_i:24+-val_i:25+-val_i:26+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:38+-val_i:41+-val_i:42+-val_i:44+-val_i:48+-val_i:49+-val_i:52+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:58+-val_i:61+-val_i:62+-val_i:64+-val_i:66+-val_i:67+-val_i:70+-val_i:72+-val_i:73&group.main=true&group.cache.percent=100&fq={!+cache%3Dtrue+tag%3Dt}id:RAND1938553725+OR+val_i:{00024+TO+00071}&fq={!+cache%3Dfalse+cost%3D149+tag%3Dt}val_s:{00069+TO+00120]+AND+val_s:[00070+TO+00121}&fq={!+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D48+u%3D73}"&fq={!cache%3Dfalse}+val_i:2+val_i:4+val_i:5+val_i:6+val_i:9+val_i:11+val_i:14+val_i:18+val_i:19+val_i:21+val_i:22+val_i:23+val_i:24+val_i:26+val_i:27+val_i:28+val_i:30+val_i:32+val_i:33+val_i:36+val_i:37+val_i:48+val_i:49+val_i:50+val_i:54+val_i:56+val_i:57+val_i:59+val_i:60+val_i:64+val_i:68+val_i:69+val_i:70+val_i:72&wt=xml&group.field=id&group=true} hits=0 status=0 QTime=1
[junit4] 2> 4377817 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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:7+-val_i:8+-val_i:9+-val_i:11+-val_i:12+-val_i:13+-val_i:15+-val_i:16+-val_i:19+-val_i:27+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:34+-val_i:37+-val_i:38+-val_i:39+-val_i:41+-val_i:42+-val_i:44+-val_i:45+-val_i:46+-val_i:48+-val_i:50+-val_i:52+-val_i:54+-val_i:57+-val_i:58+-val_i:60+-val_i:62+-val_i:63+-val_i:64+-val_i:70+-val_i:71&group.main=true&group.cache.percent=100&fq=-_query_:"val_s:[00045+TO+00073]"&wt=xml&group.field=id&group=true} hits=20 status=0 QTime=1
[junit4] 2> 4377820 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dtrue}+val_i:0+val_i:2+val_i:4+val_i:5+val_i:6+val_i:9+val_i:10+val_i:12+val_i:13+val_i:14+val_i:16+val_i:17+val_i:18+val_i:19+val_i:20+val_i:25+val_i:26+val_i:32+val_i:35+val_i:37+val_i:40+val_i:41+val_i:42+val_i:48+val_i:49+val_i:50+val_i:54+val_i:58+val_i:59+val_i:62+val_i:63+val_i:64+val_i:70+val_i:73&fq={!cache%3Dfalse+cost%3D196+tag%3Dt}+-val_i:0+-val_i:1+-val_i:3+-val_i:4+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:18+-val_i:19+-val_i:21+-val_i:23+-val_i:25+-val_i:28+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:36+-val_i:37+-val_i:38+-val_i:42+-val_i:44+-val_i:46+-val_i:47+-val_i:51+-val_i:52+-val_i:55+-val_i:56+-val_i:57+-val_i:58+-val_i:59+-val_i:61+-val_i:63+-val_i:64+-val_i:65+-val_i:67+-val_i:68+-val_i:69+-val_i:70+-val_i:72+-val_i:73&wt=xml} hits=15 status=0 QTime=3
[junit4] 2> 4377834 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=+-val_i:0+-val_i:2+-val_i:3+-val_i:4+-val_i:6+-val_i:7+-val_i:11+-val_i:12+-val_i:14+-val_i:17+-val_i:18+-val_i:19+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:31+-val_i:33+-val_i:34+-val_i:36+-val_i:39+-val_i:40+-val_i:42+-val_i:43+-val_i:44+-val_i:47+-val_i:48+-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:64+-val_i:65+-val_i:67+-val_i:68+-val_i:71&fq={!cache%3Dfalse+cost%3D171}+-val_i:0+-val_i:3+-val_i:4+-val_i:8+-val_i:10+-val_i:13+-val_i:14+-val_i:15+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:23+-val_i:24+-val_i:28+-val_i:29+-val_i:30+-val_i:33+-val_i:34+-val_i:41+-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:56+-val_i:58+-val_i:59+-val_i:60+-val_i:61+-val_i:62+-val_i:64+-val_i:67+-val_i:68+-val_i:70+-val_i:71&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:11+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:21+-val_i:23+-val_i:25+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:35+-val_i:36+-val_i:39+-val_i:41+-val_i:47+-val_i:49+-val_i:52+-val_i:63+-val_i:65+-val_i:67+-val_i:68+-val_i:69+-val_i:73&wt=xml} hits=7 status=0 QTime=13
[junit4] 2> 4377838 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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:{00053+TO+00073]"&q={!frange+v%3Dval_i+l%3D22+u%3D72+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dfalse+cost%3D69+tag%3Dt}+val_i:5+val_i:7+val_i:8+val_i:10+val_i:12+val_i:13+val_i:14+val_i:16+val_i:18+val_i:21+val_i:23+val_i:24+val_i:27+val_i:28+val_i:29+val_i:30+val_i:31+val_i:32+val_i:33+val_i:38+val_i:39+val_i:40+val_i:41+val_i:42+val_i:48+val_i:58+val_i:59+val_i:62+val_i:64+val_i:66+val_i:67+val_i:69+val_i:72+val_i:73&fq=-_query_:"{!frange+v%3Dval_i+l%3D22+u%3D73}"&fq={!cache%3Dfalse}+-val_i:0+-val_i:1+-val_i:4+-val_i:5+-val_i:7+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:20+-val_i:25+-val_i:26+-val_i:28+-val_i:29+-val_i:30+-val_i:31+-val_i:34+-val_i:35+-val_i:38+-val_i:39+-val_i:42+-val_i:43+-val_i:45+-val_i:48+-val_i:49+-val_i:50+-val_i:51+-val_i:52+-val_i:54+-val_i:55+-val_i:56+-val_i:57+-val_i:59+-val_i:61+-val_i:63+-val_i:65+-val_i:66+-val_i:67+-val_i:69+-val_i:71&facet=true&wt=xml} hits=0 status=0 QTime=3
[junit4] 2> 4377840 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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%3D64+u%3D73}"&q={!cache%3Dfalse+cost%3D9+tag%3Dt}+val_i:1+val_i:2+val_i:3+val_i:6+val_i:9+val_i:10+val_i:12+val_i:13+val_i:19+val_i:20+val_i:26+val_i:29+val_i:30+val_i:32+val_i:36+val_i:37+val_i:40+val_i:42+val_i:46+val_i:49+val_i:51+val_i:52+val_i:54+val_i:55+val_i:56+val_i:58+val_i:59+val_i:61+val_i:63+val_i:66+val_i:67+val_i:68+val_i:69+val_i:70+val_i:73&fq={!cache%3Dtrue}+-val_i:1+-val_i:3+-val_i:8+-val_i:11+-val_i:12+-val_i:13+-val_i:18+-val_i:19+-val_i:20+-val_i:22+-val_i:23+-val_i:24+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:39+-val_i:42+-val_i:43+-val_i:44+-val_i:46+-val_i:47+-val_i:48+-val_i:50+-val_i:55+-val_i:56+-val_i:57+-val_i:59+-val_i:62+-val_i:63+-val_i:72+-val_i:73&fq={!frange+v%3Dval_i+l%3D72+u%3D100+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 4377841 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!}-_query_:"val_i:[00021+TO+00074}"&fq={!+cost%3D159+tag%3Dt}-_query_:"val_s:[00043+TO+00074}"&wt=xml} hits=21 status=0 QTime=0
[junit4] 2> 4377844 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dtrue}+val_i:0+val_i:2+val_i:3+val_i:4+val_i:5+val_i:8+val_i:11+val_i:12+val_i:14+val_i:15+val_i:16+val_i:17+val_i:19+val_i:20+val_i:21+val_i:24+val_i:25+val_i:26+val_i:29+val_i:30+val_i:31+val_i:36+val_i:41+val_i:42+val_i:43+val_i:45+val_i:50+val_i:51+val_i:52+val_i:54+val_i:55+val_i:58+val_i:60+val_i:65+val_i:66+val_i:67+val_i:69+val_i:70+val_i:71+val_i:72+val_i:73&q={!cache%3Dfalse+cost%3D13}+val_i:0+val_i:4+val_i:5+val_i:6+val_i:8+val_i:12+val_i:13+val_i:14+val_i:16+val_i:20+val_i:22+val_i:23+val_i:24+val_i:26+val_i:28+val_i:29+val_i:30+val_i:33+val_i:34+val_i:36+val_i:37+val_i:39+val_i:41+val_i:46+val_i:47+val_i:50+val_i:54+val_i:55+val_i:56+val_i:58+val_i:59+val_i:63+val_i:65+val_i:67+val_i:70+val_i:71+val_i:72&facet=true&wt=xml} hits=37 status=0 QTime=2
[junit4] 2> 4377849 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}*:*+AND+val_i:[00050+TO+00102]&q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:2+-val_i:4+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:13+-val_i:14+-val_i:15+-val_i:19+-val_i:21+-val_i:24+-val_i:28+-val_i:29+-val_i:30+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:39+-val_i:45+-val_i:48+-val_i:50+-val_i:53+-val_i:55+-val_i:57+-val_i:58+-val_i:60+-val_i:65+-val_i:66+-val_i:67+-val_i:69+-val_i:70+-val_i:71&fq=-_query_:"{!frange+v%3Dval_i+l%3D25+u%3D73}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D34+u%3D73}"&fq={!cache%3Dfalse}+-val_i:3+-val_i:4+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:12+-val_i:16+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:37+-val_i:39+-val_i:40+-val_i:41+-val_i:42+-val_i:46+-val_i:49+-val_i:50+-val_i:53+-val_i:54+-val_i:55+-val_i:59+-val_i:61+-val_i:62+-val_i:65+-val_i:71+-val_i:72+-val_i:73&facet=true&wt=xml} hits=6 status=0 QTime=4
[junit4] 2> 4377851 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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:9+-val_i:10+-val_i:13+-val_i:17+-val_i:18+-val_i:20+-val_i:22+-val_i:23+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:36+-val_i:38+-val_i:40+-val_i:41+-val_i:44+-val_i:50+-val_i:52+-val_i:53+-val_i:54+-val_i:55+-val_i:56+-val_i:57+-val_i:61+-val_i:62+-val_i:63+-val_i:64+-val_i:65+-val_i:66+-val_i:67+-val_i:70+-val_i:71+-val_i:72&q=-_query_:"{!frange+v%3Dval_i+l%3D7+u%3D73}"&fq={!cache%3Dtrue}+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:9+-val_i:11+-val_i:12+-val_i:16+-val_i:17+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:25+-val_i:26+-val_i:28+-val_i:29+-val_i:36+-val_i:37+-val_i:42+-val_i:45+-val_i:47+-val_i:49+-val_i:50+-val_i:53+-val_i:56+-val_i:58+-val_i:59+-val_i:61+-val_i:66+-val_i:70&fq={!frange+v%3Dval_i+l%3D48+u%3D85+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 4377852 INFO (TEST-TestFiltering.testRandomFiltering-seed#[941028414E3EFF6C]) [ ] 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:3+-val_i:4+-val_i:5+-val_i:7+-val_i:9+-val_i:11+-val_i:17+-val_i:19+-val_i:20+-val_i:23+-val_i:25+-val_i:28+-val_i:34+-val_i:35+-val_i:36+-val_i:39+-val_i:44+-val_i:51+-val_i:52+-val_i:53+-val_
[...truncated too long message...]
2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 6) Thread[id=214646, name=h2sc-15992-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:360)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:184)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:135)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$$Lambda$103/1319282351.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$62/1315339641.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> 7) Thread[id=214642, name=h2sc-15990-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:360)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:184)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:135)
[junit4] 2> at org.eclipse.jetty.io.ManagedSelector$$Lambda$103/1319282351.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$62/1315339641.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=DummyCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=1563, maxDocsPerChunk=9, blockShift=7), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=1563, maxDocsPerChunk=9, blockSize=7)), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=sr-Latn-ME, timezone=Asia/Dhaka
[junit4] 2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=10,free=140115600,total=535298048
[junit4] 2> NOTE: All tests run in this JVM: [BlockJoinFacetDistribTest, UninvertDocValuesMergePolicyTest, AutoscalingHistoryHandlerTest, OpenExchangeRatesOrgProviderTest, FacetPivotSmallTest, ClusterStateUpdateTest, TestSubQueryTransformerCrossCore, TestCustomSort, SecurityConfHandlerTest, MigrateRouteKeyTest, TestManagedSchemaAPI, AnalysisErrorHandlingTest, ForceLeaderTest, TestBackupRepositoryFactory, TestTolerantSearch, SuggesterTest, TestNamedUpdateProcessors, TestIncrementalCoreBackup, AddSchemaFieldsUpdateProcessorFactoryTest, OverseerStatusTest, TestConfig, FieldMutatingUpdateProcessorTest, BasicAuthStandaloneTest, TestRebalanceLeaders, TestSchemaField, PluginInfoTest, MBeansHandlerTest, TestSchemaDesignerConfigSetHelper, TestCollectionAPIs, BasicDistributedZk2Test, TestSearchPerf, SolrIndexMetricsTest, ZkDynamicConfigTest, ZkStateWriterTest, TestRandomDVFaceting, HighlighterConfigTest, TestCloudJSONFacetSKG, TestSchemaVersionResource, SolrCloudExampleTest, ByteBuffersDirectoryFactoryTest, GraphQueryTest, TestTestInjection, TestFastOutputStream, RestartWhileUpdatingTest, TestBinaryField, DocValuesTest, TestConfigSets, BooleanFieldTest, TestCollectionAPI, TestLazyCores, ClassificationUpdateProcessorFactoryTest, NodeMutatorTest, TestOnReconnectListenerSupport, HdfsThreadLeakTest, PingRequestHandlerTest, TestReplicaProperties, FuzzySearchTest, ConjunctionSolrSpellCheckerTest, TestPseudoReturnFields, HdfsBasicDistributedZkTest, TestShortCircuitedRequests, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestPostingsSolrHighlighter, NoCacheHeaderTest, SuggestComponentContextFilterQueryTest, ActionThrottleTest, TestLMJelinekMercerSimilarityFactory, TestLegacyTerms, SubstringBytesRefFilterTest, TestChildDocTransformer, TestStressIncrementalBackup, TestFieldCacheSortRandom, TestRemoteStreaming, NestedAtomicUpdateTest, TestPivotHelperCode, VMParamsZkACLAndCredentialsProvidersTest, TriggerEventQueueTest, HttpTriggerListenerTest, TestSimUtils, CdcrVersionReplicationTest, V2CollectionBackupsAPIMappingTest, SolrMetricsIntegrationTest, TestStressUserVersions, AsyncCallRequestStatusResponseTest, TestCloudInspectUtil, TestCustomStream, V2StandaloneTest, TestSimNodeLostTrigger, TestTextField, TestFieldTypeResource, TestDistribIDF, TestScoreJoinQPScore, AggValueSourceTest, TestImplicitCoreProperties, IndexSizeEstimatorTest, IndexSchemaTest, TermsComponentTest, DistributedQueryElevationComponentTest, TestCursorMarkWithoutUniqueKey, LeaderVoteWaitTimeoutTest, SchemaVersionSpecificBehaviorTest, QueryEqualityTest, TestGraphMLResponseWriter, TestWordDelimiterFilterFactory, SearchRateTriggerTest, TestDocTermOrdsUninvertLimit, JWTVerificationkeyResolverTest, SearchHandlerTest, TestPullReplicaWithAuth, TestLeaderElectionWithEmptyReplica, TestMaxScoreQueryParser, TestSimComputePlanAction, TestAuthorizationFramework, DeleteShardTest, TestManagedSynonymFilterFactory, TestCSVLoader, MetricTriggerIntegrationTest, MetricTriggerTest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, NodeLostTriggerTest, ScheduledMaintenanceTriggerTest, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, HdfsNNFailoverTest, HdfsRecoverLeaseTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestConfigOverlay, TestCoreContainer, TestMergePolicyConfig, TestMinimalConfig, TestNRTOpen, TestReloadAndDeleteDocs, TestSolrConfigHandler, TestSolrCloudSnapshots, TestReplicationHandlerBackup, TestReplicationHandlerDiskOverFlow, TestSQLHandler, XmlUpdateRequestHandlerTest, CoreAdminOperationTest, CoreAdminRequestStatusTest, ShowFileRequestHandlerTest, ZookeeperStatusHandlerFailureTest, CloudReplicaSourceTest, CustomHighlightComponentTest, DebugComponentTest, DistributedDebugComponentTest, DistributedExpandComponentTest, DistributedFacetPivotLongTailTest, DistributedMLTComponentTest, TestDistributedStatsComponentCardinality, TestCollationField, TestManagedSchemaThreadSafety, UUIDFieldTest, AnalyticsMergeStrategyTest, RankQueryTest, SignificantTermsQParserPluginTest, SortSpecParsingTest, TestAddFieldRealTimeGet, TestCaffeineCache, TestFastLRUCache, TestFilteredDocIdSet, TestFiltering]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestFiltering -Dtests.seed=941028414E3EFF6C -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=sr-Latn-ME -Dtests.timezone=Asia/Dhaka -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | TestFiltering (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([941028414E3EFF6C]:0)
[junit4] Completed [947/947 (1!)] on J2 in 7212.86s, 4 tests, 2 errors <<< FAILURES!
[...truncated 54224 lines...]