Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/14356/
Java: 32bit/jdk1.8.0_60 -server -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.search.TestFiltering.testRandomFiltering

Error Message:
FAILURE: indexSize=2 iiter=4 qiter=474 request=[q, {! cost=172 
tag=t}val_s:{00000 TO 00001], facet, true, facet.query, *:*, facet.query, 
{!key=multiSelect ex=t}*:*, facet.query, {!key=facetQuery}val_s:[00001 TO 
00001]]

Stack Trace:
java.lang.AssertionError: FAILURE: indexSize=2 iiter=4 qiter=474 request=[q, {! 
cost=172 tag=t}val_s:{00000 TO 00001], facet, true, facet.query, *:*, 
facet.query, {!key=multiSelect ex=t}*:*, facet.query, 
{!key=facetQuery}val_s:[00001 TO 00001]]
        at 
__randomizedtesting.SeedInfo.seed([20925532CE885922:3FF1F6F72BAF192B]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.search.TestFiltering.testRandomFiltering(TestFiltering.java:419)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:873)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 9528 lines...]
   [junit4] Suite: org.apache.solr.search.TestFiltering
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001
   [junit4]   2> 227185 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 227186 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 227186 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 227186 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 227186 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 227199 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 227205 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 227217 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 227217 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.s.IndexSchema 
Reading Solr Schema from 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema_latest.xml
   [junit4]   2> 227220 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.s.IndexSchema 
[null] Schema name=example
   [junit4]   2> 227254 WARN  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.s.SpatialRecursivePrefixTreeFieldType units parameter is deprecated, 
please use distanceUnits instead for field types with class 
SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 227256 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 227261 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 227263 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 227264 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 227264 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr
   [junit4]   2> 227264 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 227274 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 9196432
   [junit4]   2> 227274 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 227274 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 227274 WARN  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/lib).
   [junit4]   2> 227278 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 227279 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 227279 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.l.LogWatcher 
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 227279 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.l.LogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 227279 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 227279 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 227280 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] o.a.s.c.SolrCore 
Created CoreDescriptor: {name=collection1, config=solrconfig.xml, 
transient=false, schema=schema_latest.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, instanceDir=collection1, 
collection=collection1, 
absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/,
 
dataDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001,
 shard=shard1}
   [junit4]   2> 227280 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 227280 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 227280 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 227291 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 227297 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 227310 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 227310 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema_latest.xml
   [junit4]   2> 227313 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 227348 WARN  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.s.SpatialRecursivePrefixTreeFieldType units parameter is deprecated, 
please use distanceUnits instead for field types with class 
SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 227349 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 227352 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 227353 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 227355 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 227355 INFO  (coreLoadExecutor-821-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 227355 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 227355 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/],
 dataDir=[null]
   [junit4]   2> 227355 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b699fe
   [junit4]   2> 227356 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001
   [junit4]   2> 227356 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/index/
   [junit4]   2> 227356 WARN  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 227356 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/index
   [junit4]   2> 227356 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 227357 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_1,generation=1}
   [junit4]   2> 227357 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 227357 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 227359 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 227360 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 227360 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 227360 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 227361 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 227361 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 227362 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 227363 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 227364 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 227365 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 227365 WARN  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated 
<admin/gettableFiles>, Please update your config to use the 
ShowFileRequestHandler.
   [junit4]   2> 227366 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 227366 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 227366 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 227366 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=67.548828125, 
floorSegmentMB=2.166015625, forceMergeDeletesPctAllowed=4.100062074590256, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.555097336593643
   [junit4]   2> 227366 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_1,generation=1}
   [junit4]   2> 227366 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 227367 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@9c64[collection1] main
   [junit4]   2> 227367 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 227367 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 227367 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 227367 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 227408 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 227524 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 227524 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 227524 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 227525 INFO  (coreLoadExecutor-821-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 227525 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 227526 INFO  
(searcherExecutor-822-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@9c64[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 227526 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 227528 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testRandomFiltering
   [junit4]   2> 227528 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 227529 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{deleteByQuery=*:*} 0 0
   [junit4]   2> 227532 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[0]} 0 0
   [junit4]   2> 227532 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[1]} 0 0
   [junit4]   2> 227532 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[1]} 0 0
   [junit4]   2> 227532 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[2]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[2]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[3]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[4]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[4]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[5]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[6]} 0 0
   [junit4]   2> 227533 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227536 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_2,generation=2}
   [junit4]   2> 227536 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 227544 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@45f9ff[collection1] main
   [junit4]   2> 227544 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227545 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@45f9ff[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)))}
   [junit4]   2> 227545 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 11
   [junit4]   2> 227546 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[7]} 0 0
   [junit4]   2> 227546 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227549 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_3,generation=3}
   [junit4]   2> 227549 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 227550 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1ef6f92[collection1] main
   [junit4]   2> 227550 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227550 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ef6f92[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)
 Uninverting(_1(6.0.0):c1)))}
   [junit4]   2> 227551 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 4
   [junit4]   2> 227551 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[8]} 0 0
   [junit4]   2> 227552 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227554 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_4,generation=4}
   [junit4]   2> 227554 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 4
   [junit4]   2> 227554 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@74baf2[collection1] main
   [junit4]   2> 227554 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227555 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@74baf2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)
 Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1)))}
   [junit4]   2> 227555 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 3
   [junit4]   2> 227556 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[9]} 0 0
   [junit4]   2> 227556 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[10]} 0 0
   [junit4]   2> 227556 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227558 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_5,generation=5}
   [junit4]   2> 227559 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 5
   [junit4]   2> 227559 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@171ddcc[collection1] main
   [junit4]   2> 227559 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227560 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@171ddcc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)
 Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1) 
Uninverting(_3(6.0.0):c2)))}
   [junit4]   2> 227560 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 3
   [junit4]   2> 227561 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[11]} 0 0
   [junit4]   2> 227561 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[11]} 0 0
   [junit4]   2> 227561 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[12]} 0 0
   [junit4]   2> 227561 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[12]} 0 0
   [junit4]   2> 227561 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[13]} 0 0
   [junit4]   2> 227561 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[14]} 0 0
   [junit4]   2> 227562 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[15]} 0 0
   [junit4]   2> 227562 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[16]} 0 0
   [junit4]   2> 227562 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[17]} 0 0
   [junit4]   2> 227562 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[18]} 0 0
   [junit4]   2> 227562 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[19]} 0 0
   [junit4]   2> 227562 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[20]} 0 0
   [junit4]   2> 227563 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[21]} 0 0
   [junit4]   2> 227563 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[22]} 0 0
   [junit4]   2> 227563 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[23]} 0 0
   [junit4]   2> 227563 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[24]} 0 0
   [junit4]   2> 227563 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[25]} 0 0
   [junit4]   2> 227563 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[26]} 0 0
   [junit4]   2> 227564 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227566 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_6,generation=6}
   [junit4]   2> 227566 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 6
   [junit4]   2> 227567 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@bd007e[collection1] main
   [junit4]   2> 227567 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227567 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@bd007e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)
 Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1) Uninverting(_3(6.0.0):c2) 
Uninverting(_4(6.0.0):c18/2:delGen=1)))}
   [junit4]   2> 227568 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 4
   [junit4]   2> 227568 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[27]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[28]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[28]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[29]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[30]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[31]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[32]} 0 0
   [junit4]   2> 227569 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[33]} 0 0
   [junit4]   2> 227570 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[34]} 0 0
   [junit4]   2> 227570 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[35]} 0 0
   [junit4]   2> 227570 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[36]} 0 0
   [junit4]   2> 227570 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[36]} 0 0
   [junit4]   2> 227571 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[37]} 0 0
   [junit4]   2> 227571 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[38]} 0 0
   [junit4]   2> 227571 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[39]} 0 0
   [junit4]   2> 227571 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[40]} 0 0
   [junit4]   2> 227571 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[41]} 0 0
   [junit4]   2> 227571 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227574 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_7,generation=7}
   [junit4]   2> 227574 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 7
   [junit4]   2> 227575 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@a502bd[collection1] main
   [junit4]   2> 227575 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227575 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a502bd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)
 Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1) Uninverting(_3(6.0.0):c2) 
Uninverting(_4(6.0.0):c18/2:delGen=1) Uninverting(_5(6.0.0):c17/2:delGen=1)))}
   [junit4]   2> 227576 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 4
   [junit4]   2> 227576 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[42]} 0 0
   [junit4]   2> 227576 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 227579 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_7,generation=7}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_8,generation=8}
   [junit4]   2> 227579 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 8
   [junit4]   2> 227579 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@8b84e4[collection1] main
   [junit4]   2> 227580 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 227580 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@8b84e4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c10/3:delGen=1)
 Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1) Uninverting(_3(6.0.0):c2) 
Uninverting(_4(6.0.0):c18/2:delGen=1) Uninverting(_5(6.0.0):c17/2:delGen=1) 
Uninverting(_6(6.0.0):c1)))}
   [junit4]   2> 227580 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 4
   [junit4]   2> 227581 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} 
hits=43 status=0 QTime=0 
   [junit4]   2> 227610 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D6+u%3D44+cost%3D160}&group.main=true&fq={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:2+val_i:6+val_i:8+val_i:9+val_i:10+val_i:11+val_i:14+val_i:18+val_i:19+val_i:23+val_i:24+val_i:25+val_i:26+val_i:30+val_i:32+val_i:35+val_i:36+val_i:38+val_i:41+val_i:42&wt=xml&group.field=id&group=true}
 hits=19 status=0 QTime=26 
   [junit4]   2> 227616 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+val_i:2+val_i:4+val_i:6+val_i:9+val_i:10+val_i:11+val_i:13+val_i:14+val_i:15+val_i:16+val_i:17+val_i:22+val_i:25+val_i:27+val_i:28+val_i:29+val_i:32+val_i:34+val_i:36+val_i:37+val_i:38+val_i:39+val_i:40+val_i:41+val_i:42&q={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:2+val_i:5+val_i:7+val_i:8+val_i:12+val_i:18+val_i:19+val_i:24+val_i:28+val_i:29+val_i:30+val_i:31+val_i:34+val_i:37+val_i:38+val_i:39&fq={!cache%3Dfalse}+val_i:1+val_i:4+val_i:6+val_i:7+val_i:9+val_i:10+val_i:14+val_i:16+val_i:18+val_i:25+val_i:27+val_i:28+val_i:30+val_i:33+val_i:36+val_i:39+val_i:41+val_i:42&fq={!cache%3Dfalse+tag%3Dt}+-val_i:2+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:22+-val_i:27+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:37+-val_i:41&facet=true&wt=xml}
 hits=2 status=0 QTime=5 
   [junit4]   2> 227620 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}+val_i:0+val_i:2+val_i:3+val_i:4+val_i:5+val_i:6+val_i:7+val_i:9+val_i:11+val_i:12+val_i:15+val_i:16+val_i:17+val_i:20+val_i:21+val_i:22+val_i:27+val_i:28+val_i:29+val_i:30+val_i:31+val_i:32+val_i:33+val_i:39+val_i:41+val_i:42&q={!+cache%3Dtrue+tag%3Dt}val_i:[00039+TO+00076]&fq={!cache%3Dfalse+cost%3D7}+val_i:1+val_i:2+val_i:7+val_i:8+val_i:9+val_i:10+val_i:12+val_i:16+val_i:17+val_i:18+val_i:21+val_i:22+val_i:24+val_i:25+val_i:26+val_i:27+val_i:29+val_i:31+val_i:33+val_i:34+val_i:35+val_i:37+val_i:38+val_i:40+val_i:41+val_i:42&facet=true&wt=xml}
 hits=3 status=0 QTime=3 
   [junit4]   2> 227624 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D51}val_i:[00040+TO+00082]+AND+val_i:{00039+TO+00083}&q={!cache%3Dtrue+tag%3Dt}+val_i:1+val_i:3+val_i:5+val_i:6+val_i:7+val_i:12+val_i:15+val_i:16+val_i:20+val_i:21+val_i:24+val_i:25+val_i:26+val_i:27+val_i:32+val_i:33+val_i:35+val_i:36+val_i:38+val_i:40&fq={!cache%3Dfalse+cost%3D141}+val_i:1+val_i:2+val_i:4+val_i:5+val_i:7+val_i:8+val_i:11+val_i:12+val_i:13+val_i:15+val_i:20+val_i:23+val_i:24+val_i:26+val_i:27+val_i:29+val_i:31+val_i:33+val_i:35+val_i:38+val_i:40+val_i:41+val_i:42&fq={!frange+v%3Dval_i+l%3D22+u%3D44+cache%3Dtrue+tag%3Dt}&facet=true&wt=xml}
 hits=7 status=0 QTime=3 
   [junit4]   2> 227627 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=-_query_:"val_i:{00019+TO+00042]"&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:1+val_i:3+val_i:4+val_i:5+val_i:11+val_i:15+val_i:16+val_i:17+val_i:20+val_i:21+val_i:24+val_i:26+val_i:27+val_i:28+val_i:29+val_i:30+val_i:31+val_i:33+val_i:35+val_i:36+val_i:39+val_i:40+val_i:41+val_i:42&fq={!cache%3Dfalse+cost%3D119}+val_i:0+val_i:1+val_i:2+val_i:4+val_i:5+val_i:7+val_i:8+val_i:9+val_i:16+val_i:17+val_i:19+val_i:20+val_i:21+val_i:22+val_i:24+val_i:28+val_i:32+val_i:33+val_i:38+val_i:40+val_i:41+val_i:42&fq={!+tag%3Dt}-_query_:"val_s:{00026+TO+00042]"&wt=xml}
 hits=6 status=0 QTime=2 
   [junit4]   2> 227629 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cache%3Dfalse+cost%3D192+tag%3Dt}val_s:[00037+TO+00054}&fq=val_s:{00039+TO+00070]&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 227632 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:1+-val_i:2+-val_i:5+-val_i:6+-val_i:8+-val_i:9+-val_i:10+-val_i:12+-val_i:14+-val_i:16+-val_i:17+-val_i:18+-val_i:20+-val_i:22+-val_i:23+-val_i:25+-val_i:28+-val_i:30+-val_i:31+-val_i:32+-val_i:34+-val_i:35+-val_i:36+-val_i:41+-val_i:42&q={!frange+v%3Dval_i+l%3D6+u%3D44+cache%3Dtrue+tag%3Dt}&fq={!frange+v%3Dval_i+l%3D24+u%3D50+cost%3D86+tag%3Dt}&fq={!cache%3Dfalse+cost%3D117}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:16+-val_i:18+-val_i:19+-val_i:20+-val_i:22+-val_i:27+-val_i:33+-val_i:37+-val_i:38+-val_i:39&facet=true&wt=xml}
 hits=14 status=0 QTime=2 
   [junit4]   2> 227637 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:[00001+TO+00042]"&q={!cache%3Dtrue}+-val_i:0+-val_i:3+-val_i:4+-val_i:5+-val_i:7+-val_i:10+-val_i:11+-val_i:14+-val_i:16+-val_i:17+-val_i:18+-val_i:22+-val_i:23+-val_i:25+-val_i:26+-val_i:27+-val_i:30+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:40+-val_i:42&fq={!+cache%3Dfalse}val_i:[00008+TO+00040}&fq={!+cache%3Dfalse+tag%3Dt}val_i:[00019+TO+00035}&fq={!cache%3Dfalse+cost%3D118}+val_i:1+val_i:5+val_i:8+val_i:9+val_i:11+val_i:12+val_i:13+val_i:14+val_i:15+val_i:16+val_i:17+val_i:22+val_i:23+val_i:25+val_i:26+val_i:30+val_i:31+val_i:33+val_i:34+val_i:38&fq=-_query_:"val_s:{00020+TO+00042]"&facet=true&wt=xml}
 hits=0 status=0 QTime=4 
   [junit4]   2> 227640 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:[00029+TO+00060]&q={!+tag%3Dt}val_i:[00019+TO+00041]&fq={!+cost%3D63}-_query_:"val_i:{00036+TO+00042]"&fq={!+cost%3D25}val_i:[00000+TO+00020]&fq={!cache%3Dtrue}+-val_i:0+-val_i:4+-val_i:5+-val_i:10+-val_i:12+-val_i:14+-val_i:17+-val_i:18+-val_i:21+-val_i:23+-val_i:24+-val_i:26+-val_i:28+-val_i:29+-val_i:31+-val_i:33+-val_i:38+-val_i:39+-val_i:40+-val_i:41&facet=true&wt=xml}
 hits=2 status=0 QTime=2 
   [junit4]   2> 227642 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cost%3D166+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D16+u%3D42}"&fq={!cache%3Dfalse+cost%3D66}+val_i:0+val_i:1+val_i:4+val_i:6+val_i:10+val_i:12+val_i:14+val_i:15+val_i:18+val_i:19+val_i:21+val_i:22+val_i:23+val_i:24+val_i:26+val_i:28+val_i:30+val_i:31+val_i:32+val_i:34+val_i:35+val_i:37+val_i:39+val_i:40+val_i:41&wt=xml}
 hits=8 status=0 QTime=1 
   [junit4]   2> 227644 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:2+val_i:3+val_i:4+val_i:5+val_i:6+val_i:8+val_i:10+val_i:13+val_i:16+val_i:18+val_i:19+val_i:20+val_i:21+val_i:23+val_i:27+val_i:30+val_i:31+val_i:32+val_i:33+val_i:34+val_i:35+val_i:36+val_i:37+val_i:38+val_i:39+val_i:41+val_i:42&q={!cache%3Dfalse+cost%3D86+tag%3Dt}+-val_i:1+-val_i:2+-val_i:3+-val_i:8+-val_i:9+-val_i:10+-val_i:13+-val_i:14+-val_i:15+-val_i:19+-val_i:20+-val_i:21+-val_i:23+-val_i:28+-val_i:29+-val_i:31+-val_i:33+-val_i:36+-val_i:37+-val_i:38+-val_i:41&facet=true&wt=xml}
 hits=22 status=0 QTime=2 
   [junit4]   2> 227644 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D40+u%3D61+cache%3Dtrue+tag%3Dt}&wt=xml} hits=3 
status=0 QTime=0 
   [junit4]   2> 227648 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D4}-_query_:"val_s:[00015+TO+00043}"&q=-_query_:"{!frange+v%3Dval_i+l%3D23+u%3D42}"&fq={!cache%3Dfalse+cost%3D56+tag%3Dt}+-val_i:0+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:11+-val_i:12+-val_i:13+-val_i:15+-val_i:16+-val_i:17+-val_i:20+-val_i:25+-val_i:26+-val_i:28+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:37+-val_i:38+-val_i:40+-val_i:41+-val_i:42&fq={!cache%3Dfalse+cost%3D18+tag%3Dt}+-val_i:0+-val_i:1+-val_i:4+-val_i:7+-val_i:9+-val_i:12+-val_i:14+-val_i:15+-val_i:17+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:32+-val_i:34+-val_i:39+-val_i:41+-val_i:42&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:[00014+TO+00042]"&facet=true&wt=xml}
 hits=3 status=0 QTime=3 
   [junit4]   2> 227651 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:1+val_i:2+val_i:3+val_i:6+val_i:10+val_i:12+val_i:17+val_i:18+val_i:19+val_i:20+val_i:21+val_i:25+val_i:28+val_i:29+val_i:31+val_i:32+val_i:34+val_i:35+val_i:36+val_i:41&q={!cache%3Dfalse+cost%3D30+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:4+val_i:7+val_i:8+val_i:11+val_i:12+val_i:14+val_i:15+val_i:17+val_i:22+val_i:23+val_i:24+val_i:25+val_i:26+val_i:29+val_i:30+val_i:31+val_i:32+val_i:33+val_i:34+val_i:36+val_i:40+val_i:42&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:8+-val_i:10+-val_i:11+-val_i:13+-val_i:15+-val_i:16+-val_i:21+-val_i:22+-val_i:24+-val_i:26+-val_i:27+-val_i:29+-val_i:38+-val_i:39+-val_i:40+-val_i:41+-val_i:42&facet=true&wt=xml}
 hits=13 status=0 QTime=2 
   [junit4]   2> 227652 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D11+tag%3Dt}+-val_i:0+-val_i:3+-val_i:4+-val_i:7+-val_i:13+-val_i:18+-val_i:21+-val_i:23+-val_i:26+-val_i:30+-val_i:35+-val_i:39+-val_i:40&fq={!cache%3Dfalse}+-val_i:0+-val_i:2+-val_i:3+-val_i:4+-val_i:7+-val_i:9+-val_i:15+-val_i:18+-val_i:19+-val_i:20+-val_i:23+-val_i:25+-val_i:26+-val_i:27+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:37+-val_i:38+-val_i:39&wt=xml}
 hits=18 status=0 QTime=1 
   [junit4]   2> 227658 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+val_i:1+val_i:2+val_i:8+val_i:9+val_i:12+val_i:13+val_i:15+val_i:17+val_i:20+val_i:21+val_i:24+val_i:25+val_i:27+val_i:29+val_i:30+val_i:31+val_i:32+val_i:34+val_i:35+val_i:37+val_i:39+val_i:40&q={!+cache%3Dfalse+tag%3Dt}val_s:{00014+TO+00053}&fq={!cache%3Dfalse+cost%3D13}+val_i:0+val_i:4+val_i:5+val_i:6+val_i:8+val_i:9+val_i:12+val_i:14+val_i:19+val_i:21+val_i:22+val_i:24+val_i:25+val_i:31+val_i:32+val_i:34+val_i:35+val_i:36+val_i:37&fq={!cache%3Dtrue+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:12+-val_i:13+-val_i:15+-val_i:16+-val_i:17+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:31+-val_i:33+-val_i:34+-val_i:35+-val_i:37+-val_i:40+-val_i:41&fq={!cache%3Dtrue}+-val_i:2+-val_i:7+-val_i:11+-val_i:14+-val_i:15+-val_i:18+-val_i:21+-val_i:27+-val_i:28+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:39+-val_i:42&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:1+val_i:2+val_i:3+val_i:4+val_i:9+val_i:10+val_i:11+val_i:13+val_i:15+val_i:16+val_i:17+val_i:19+val_i:23+val_i:24+val_i:25+val_i:26+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:42&facet=true&wt=xml}
 hits=2 status=0 QTime=5 
   [junit4]   2> 227660 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D10}+val_i:4+val_i:5+val_i:6+val_i:9+val_i:10+val_i:11+val_i:12+val_i:13+val_i:14+val_i:17+val_i:19+val_i:21+val_i:22+val_i:25+val_i:26+val_i:29+val_i:31+val_i:32+val_i:33+val_i:34+val_i:35+val_i:36+val_i:40+val_i:42&fq={!cache%3Dfalse+cost%3D22+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:3+-val_i:7+-val_i:9+-val_i:20+-val_i:21+-val_i:23+-val_i:24+-val_i:26+-val_i:27+-val_i:28+-val_i:31+-val_i:34+-val_i:37+-val_i:40+-val_i:41+-val_i:42&wt=xml}
 hits=17 status=0 QTime=1 
   [junit4]   2> 227661 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+tag%3Dt}+-val_i:0+-val_i:6+-val_i:8+-val_i:9+-val_i:11+-val_i:15+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:23+-val_i:27+-val_i:32+-val_i:34+-val_i:36+-val_i:37+-val_i:38+-val_i:40+-val_i:42&fq={!cache%3Dfalse+cost%3D160}+-val_i:0+-val_i:1+-val_i:4+-val_i:5+-val_i:7+-val_i:9+-val_i:11+-val_i:12+-val_i:13+-val_i:15+-val_i:16+-val_i:18+-val_i:20+-val_i:23+-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:37+-val_i:40+-val_i:42&wt=xml}
 hits=9 status=0 QTime=1 
   [junit4]   2> 227664 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D166}-_query_:"val_i:{00006+TO+00042]"&q={!+cache%3Dfalse+cost%3D58}-_query_:"{!frange+v%3Dval_i+l%3D30+u%3D42}"&fq=+-val_i:1+-val_i:3+-val_i:6+-val_i:8+-val_i:10+-val_i:11+-val_i:15+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:22+-val_i:26+-val_i:28+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:38+-val_i:40+-val_i:41+-val_i:42&fq={!+cache%3Dfalse}-_query_:"val_s:{00016+TO+00043}"&facet=true&wt=xml}
 hits=9 status=0 QTime=2 
   [junit4]   2> 227665 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cache%3Dtrue+tag%3Dt}val_s:{00028+TO+00062]+AND+val_s:[00029+TO+00063}&wt=xml}
 hits=14 status=0 QTime=0 
   [junit4]   2> 227670 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery}val_s:[00015+TO+00046]&q=-_query_:"val_i:[00021+TO+00042]"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:2+-val_i:4+-val_i:11+-val_i:13+-val_i:14+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:21+-val_i:26+-val_i:28+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:35+-val_i:37+-val_i:38+-val_i:39&fq=-_query_:"val_i:[00034+TO+00042]"&fq={!cache%3Dfalse+cost%3D44}+-val_i:1+-val_i:2+-val_i:5+-val_i:6+-val_i:7+-val_i:9+-val_i:12+-val_i:13+-val_i:15+-val_i:16+-val_i:18+-val_i:20+-val_i:25+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:32+-val_i:34+-val_i:35+-val_i:37+-val_i:38+-val_i:39+-val_i:40&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:2+-val_i:6+-val_i:7+-val_i:9+-val_i:15+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:24+-val_i:26+-val_i:27+-val_i:30+-val_i:32+-val_i:37+-val_i:39+-val_i:42&facet=true&wt=xml}
 hits=4 status=0 QTime=4 
   [junit4]   2> 227672 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cost%3D89}*:*+AND+val_s:[00024+TO+00066]&fq={!frange+v%3Dval_i+l%3D22+u%3D59}&fq=val_i:{00022+TO+00038]&fq=-_query_:"val_s:[00004+TO+00042]"&wt=xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 227676 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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}id:RAND47126110+OR+val_s:{00016+TO+00048}&q={!+cache%3Dtrue+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D24+u%3D42}"&fq={!cache%3Dfalse}+val_i:1+val_i:5+val_i:7+val_i:12+val_i:14+val_i:19+val_i:22+val_i:24+val_i:26+val_i:28+val_i:29+val_i:33+val_i:34+val_i:40+val_i:42&fq={!cache%3Dfalse+cost%3D26}+val_i:0+val_i:1+val_i:3+val_i:4+val_i:7+val_i:14+val_i:16+val_i:19+val_i:21+val_i:25+val_i:26+val_i:27+val_i:29+val_i:33+val_i:36+val_i:40+val_i:41&fq=val_i:{00029+TO+00051]&fq=+val_i:0+val_i:1+val_i:3+val_i:5+val_i:7+val_i:8+val_i:13+val_i:18+val_i:19+val_i:20+val_i:23+val_i:26+val_i:28+val_i:29+val_i:32+val_i:33+val_i:34+val_i:36+val_i:38&facet=true&wt=xml}
 hits=0 status=0 QTime=3 
   [junit4]   2> 227677 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*+AND+val_s:{00039+TO+00059}&fq=-_query_:"val_i:[00027+TO+00042]"&fq=-_query_:"{!frange+v%3Dval_i+l%3D26+u%3D42}"&fq={!+cache%3Dfalse+cost%3D3}-_query_:"{!frange+v%3Dval_i+l%3D2+u%3D42}"&wt=xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 227679 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D78}-_query_:"val_i:{00021+TO+00042]"&q={!cache%3Dtrue}+val_i:0+val_i:4+val_i:11+val_i:12+val_i:13+val_i:16+val_i:17+val_i:22+val_i:25+val_i:31+val_i:36+val_i:37+val_i:38+val_i:41&facet=true&wt=xml}
 hits=14 status=0 QTime=1 
   [junit4]   2> 227680 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D4}+val_i:2+val_i:3+val_i:5+val_i:6+val_i:7+val_i:8+val_i:9+val_i:11+val_i:21+val_i:22+val_i:23+val_i:27+val_i:30+val_i:31+val_i:33+val_i:34+val_i:36+val_i:37&fq={!+cache%3Dfalse+cost%3D71+tag%3Dt}val_s:[00023+TO+00064}&wt=xml}
 hits=8 status=0 QTime=1 
   [junit4]   2> 227683 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse+cost%3D84}+-val_i:0+-val_i:1+-val_i:2+-val_i:6+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:15+-val_i:16+-val_i:17+-val_i:19+-val_i:21+-val_i:22+-val_i:26+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:37&q={!+cache%3Dfalse+cost%3D152}-_query_:"{!frange+v%3Dval_i+l%3D1+u%3D42}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:2+-val_i:3+-val_i:8+-val_i:10+-val_i:11+-val_i:14+-val_i:18+-val_i:19+-val_i:20+-val_i:23+-val_i:24+-val_i:25+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:40+-val_i:41+-val_i:42&fq=val_s:[00031+TO+00060]&facet=true&wt=xml}
 hits=0 status=0 QTime=2 
   [junit4]   2> 227686 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+}-_query_:"val_i:{00041+TO+00043}"&q={!+cache%3Dtrue+tag%3Dt}val_s:{00027+TO+00053}&fq={!frange+v%3Dval_i+l%3D26+u%3D38}&fq={!cache%3Dtrue+tag%3Dt}+val_i:0+val_i:2+val_i:3+val_i:9+val_i:10+val_i:18+val_i:29+val_i:30+val_i:31+val_i:34+val_i:37+val_i:41&fq={!+tag%3Dt}val_s:[00005+TO+00048}+OR+val_s:{00004+TO+00047]&fq=-_query_:"{!frange+v%3Dval_i+l%3D13+u%3D42}"&facet=true&wt=xml}
 hits=0 status=0 QTime=2 
   [junit4]   2> 227689 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D22}val_i:[00041+TO+00068]&q={!+tag%3Dt}val_s:{00027+TO+00050}&fq={!cache%3Dfalse+tag%3Dt}+val_i:1+val_i:3+val_i:5+val_i:7+val_i:8+val_i:9+val_i:12+val_i:17+val_i:20+val_i:21+val_i:22+val_i:23+val_i:25+val_i:27+val_i:28+val_i:29+val_i:31+val_i:32+val_i:33+val_i:35+val_i:39+val_i:41&fq={!cache%3Dfalse+cost%3D130+tag%3Dt}+-val_i:0+-val_i:4+-val_i:7+-val_i:8+-val_i:13+-val_i:16+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:24+-val_i:26+-val_i:27+-val_i:35+-val_i:37+-val_i:39+-val_i:40+-val_i:42&fq={!frange+v%3Dval_i+l%3D26+u%3D45+cache%3Dfalse}&fq={!cache%3Dtrue}+val_i:1+val_i:4+val_i:8+val_i:12+val_i:13+val_i:14+val_i:18+val_i:20+val_i:25+val_i:28+val_i:29+val_i:31+val_i:32+val_i:34+val_i:38+val_i:39+val_i:41&facet=true&wt=xml}
 hits=5 status=0 QTime=2 
   [junit4]   2> 227689 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cost%3D25+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D40+u%3D42}"&wt=xml}
 hits=40 status=0 QTime=0 
   [junit4]   2> 227692 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D62}+-val_i:0+-val_i:2+-val_i:5+-val_i:7+-val_i:9+-val_i:10+-val_i:11+-val_i:13+-val_i:15+-val_i:18+-val_i:19+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:31+-val_i:34+-val_i:35+-val_i:38+-val_i:41&q={!cache%3Dfalse+cost%3D161}+-val_i:0+-val_i:4+-val_i:7+-val_i:9+-val_i:12+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:27+-val_i:28+-val_i:29+-val_i:33+-val_i:35+-val_i:37+-val_i:38+-val_i:40+-val_i:42&fq={!+cache%3Dfalse+cost%3D178}-_query_:"val_s:{00000+TO+00043}"&facet=true&wt=xml}
 hits=0 status=0 QTime=2 
   [junit4]   2> 227695 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse}+val_i:2+val_i:5+val_i:8+val_i:9+val_i:11+val_i:12+val_i:13+val_i:14+val_i:15+val_i:19+val_i:22+val_i:25+val_i:26+val_i:28+val_i:31+val_i:34+val_i:40+val_i:42&fq={!+cache%3Dfalse+cost%3D125+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D6+u%3D42}"&fq={!cache%3Dtrue+tag%3Dt}+val_i:3+val_i:4+val_i:9+val_i:12+val_i:14+val_i:19+val_i:21+val_i:23+val_i:24+val_i:25+val_i:26+val_i:27+val_i:28+val_i:30+val_i:31+val_i:33+val_i:36+val_i:37+val_i:39+val_i:40+val_i:41+val_i:42&fq={!cache%3Dfalse+tag%3Dt}+val_i:1+val_i:3+val_i:5+val_i:7+val_i:8+val_i:11+val_i:18+val_i:21+val_i:22+val_i:24+val_i:29+val_i:30+val_i:32+val_i:34+val_i:35+val_i:39+val_i:41+val_i:42&fq={!cache%3Dtrue}+val_i:3+val_i:4+val_i:8+val_i:9+val_i:17+val_i:18+val_i:21+val_i:22+val_i:23+val_i:24+val_i:26+val_i:27+val_i:36+val_i:37&wt=xml}
 hits=0 status=0 QTime=2 
   [junit4]   2> 227697 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dtrue}+val_i:4+val_i:5+val_i:6+val_i:8+val_i:9+val_i:11+val_i:13+val_i:15+val_i:18+val_i:19+val_i:20+val_i:21+val_i:22+val_i:23+val_i:24+val_i:25+val_i:27+val_i:28+val_i:29+val_i:30+val_i:32+val_i:33+val_i:34+val_i:39+val_i:40&q={!frange+v%3Dval_i+l%3D14+u%3D45+tag%3Dt}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00022+TO+00042]"&fq={!}val_i:{00028+TO+00043}+OR+val_i:[00029+TO+00042]&facet=true&wt=xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 227700 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D27+u%3D42}"&q=*:*+AND+val_i:{00007+TO+00022}&fq={!frange+v%3Dval_i+l%3D42+u%3D42+cache%3Dtrue+tag%3Dt}&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-val_i:6+-val_i:8+-val_i:10+-val_i:13+-val_i:15+-val_i:17+-val_i:18+-val_i:22+-val_i:23+-val_i:24+-val_i:26+-val_i:28+-val_i:29+-val_i:30+-val_i:31+-val_i:32+-val_i:33+-val_i:35+-val_i:37+-val_i:38+-val_i:40&fq={!frange+v%3Dval_i+l%3D6+u%3D35+cache%3Dfalse+tag%3Dt}&facet=true&wt=xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 227708 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:5+-val_i:6+-val_i:7+-val_i:8+-val_i:10+-val_i:11+-val_i:13+-val_i:17+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:25+-val_i:26+-val_i:27+-val_i:28+-val_i:30+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:40+-val_i:42&q={!+cache%3Dfalse}val_s:{00040+TO+00058]&group.main=true&group.cache.percent=100&facet=true&wt=xml&group.field=id&group=true}
 hits=2 status=0 QTime=8 
   [junit4]   2> 227710 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D53+tag%3Dt}+val_i:3+val_i:5+val_i:9+val_i:13+val_i:22+val_i:23+val_i:28+val_i:30+val_i:31+val_i:32+val_i:35+val_i:36+val_i:37+val_i:38&fq={!cache%3Dfalse+cost%3D197+tag%3Dt}+val_i:1+val_i:4+val_i:5+val_i:7+val_i:10+val_i:11+val_i:12+val_i:14+val_i:15+val_i:17+val_i:18+val_i:19+val_i:21+val_i:22+val_i:30+val_i:33+val_i:35+val_i:37+val_i:38+val_i:39+val_i:41+val_i:42&fq=-_query_:"val_i:{00036+TO+00042]"&fq={!+cost%3D7+tag%3Dt}-_query_:"val_i:[00025+TO+00043}"&wt=xml}
 hits=2 status=0 QTime=1 
   [junit4]   2> 227712 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cache%3Dtrue+tag%3Dt}val_i:{00013+TO+00050}+AND+val_i:[00014+TO+00049]&fq={!+cost%3D22}val_s:[00011+TO+00037]+OR+id:RAND-1645352868&wt=xml}
 hits=24 status=0 QTime=1 
   [junit4]   2> 227713 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!frange+v%3Dval_i+l%3D32+u%3D55+cache%3Dfalse+tag%3Dt}&wt=xml} 
hits=11 status=0 QTime=0 
   [junit4]   2> 227716 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cost%3D175+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D25+u%3D42}"&group.main=true&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:8+-val_i:9+-val_i:13+-val_i:14+-val_i:20+-val_i:22+-val_i:23+-val_i:28+-val_i:30+-val_i:31+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:41+-val_i:42&fq=-_query_:"val_i:{00034+TO+00043}"&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00003+TO+00043}"&fq={!frange+v%3Dval_i+l%3D15+u%3D41+tag%3Dt}&wt=xml&group.field=id&group=true}
 hits=0 status=0 QTime=3 
   [junit4]   2> 227717 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=-_query_:"val_i:[00030+TO+00042]"&group.main=true&group.cache.percent=100&wt=xml&group.field=id&group=true}
 hits=30 status=0 QTime=0 
   [junit4]   2> 227721 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+-val_i:2+-val_i:3+-val_i:5+-val_i:11+-val_i:13+-val_i:14+-val_i:17+-val_i:18+-val_i:20+-val_i:22+-val_i:23+-val_i:25+-val_i:26+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:34+-val_i:35+-val_i:37+-val_i:41+-val_i:42&q={!+cache%3Dtrue+tag%3Dt}-_query_:"val_i:[00012+TO+00043}"&fq={!cache%3Dfalse}+-val_i:0+-val_i:1+-val_i:2+-val_i:4+-val_i:8+-val_i:12+-val_i:13+-val_i:14+-val_i:16+-val_i:18+-val_i:19+-val_i:21+-val_i:22+-val_i:25+-val_i:28+-val_i:31+-val_i:36+-val_i:38+-val_i:41&fq={!cache%3Dfalse}+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:8+-val_i:11+-val_i:13+-val_i:14+-val_i:18+-val_i:20+-val_i:23+-val_i:26+-val_i:27+-val_i:31+-val_i:32+-val_i:34+-val_i:36+-val_i:37+-val_i:39+-val_i:41&facet=true&wt=xml}
 hits=2 status=0 QTime=2 
   [junit4]   2> 227722 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dtrue}+-val_i:0+-val_i:1+-val_i:2+-val_i:5+-val_i:6+-val_i:7+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:22+-val_i:23+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:33+-val_i:34+-val_i:36+-val_i:38+-val_i:39+-val_i:40+-val_i:41+-val_i:42&wt=xml}
 hits=14 status=0 QTime=0 
   [junit4]   2> 227723 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D106}+val_i:0+val_i:1+val_i:6+val_i:7+val_i:8+val_i:9+val_i:10+val_i:11+val_i:13+val_i:14+val_i:15+val_i:18+val_i:19+val_i:23+val_i:24+val_i:25+val_i:28+val_i:31+val_i:32+val_i:34+val_i:35+val_i:37&fq=*:*+AND+val_i:{00007+TO+00035]&wt=xml}
 hits=17 status=0 QTime=1 
   [junit4]   2> 227725 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D137}+-val_i:0+-val_i:1+-val_i:4+-val_i:6+-val_i:8+-val_i:9+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:23+-val_i:24+-val_i:27+-val_i:28+-val_i:36+-val_i:38+-val_i:41+-val_i:42&q={!frange+v%3Dval_i+l%3D19+u%3D50+cache%3Dtrue+tag%3Dt}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00026+TO+00042]"&facet=true&wt=xml}
 hits=8 status=0 QTime=1 
   [junit4]   2> 227728 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+val_i:1+val_i:3+val_i:6+val_i:11+val_i:13+val_i:14+val_i:15+val_i:16+val_i:19+val_i:23+val_i:29+val_i:30+val_i:31+val_i:32+val_i:33+val_i:37+val_i:38+val_i:39+val_i:40+val_i:41+val_i:42&q={!frange+v%3Dval_i+l%3D23+u%3D36+tag%3Dt}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:[00007+TO+00042]"&fq={!cache%3Dtrue}+-val_i:2+-val_i:4+-val_i:6+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:17+-val_i:18+-val_i:24+-val_i:26+-val_i:27+-val_i:28+-val_i:31+-val_i:32+-val_i:35+-val_i:37+-val_i:40&facet=true&wt=xml}
 hits=0 status=0 QTime=2 
   [junit4]   2> 227730 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery+cache%3Dfalse}+val_i:4+val_i:6+val_i:7+val_i:8+val_i:9+val_i:10+val_i:12+val_i:15+val_i:18+val_i:19+val_i:20+val_i:21+val_i:27+val_i:29+val_i:30+val_i:32+val_i:36+val_i:38+val_i:41&q={!+cache%3Dtrue+tag%3Dt}val_s:{00002+TO+00028]&fq={!cache%3Dfalse+cost%3D1+tag%3Dt}+val_i:0+val_i:1+val_i:3+val_i:4+val_i:9+val_i:12+val_i:15+val_i:19+val_i:21+val_i:22+val_i:26+val_i:28+val_i:30+val_i:35+val_i:37+val_i:38+val_i:39+val_i:41&fq={!frange+v%3Dval_i+l%3D10+u%3D50+cache%3Dfalse}&facet=true&wt=xml}
 hits=7 status=0 QTime=2 
   [junit4]   2> 227732 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:1+-val_i:2+-val_i:4+-val_i:6+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:14+-val_i:19+-val_i:22+-val_i:23+-val_i:25+-val_i:26+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:33+-val_i:34+-val_i:36+-val_i:40+-val_i:41&q={!cache%3Dfalse}+-val_i:1+-val_i:2+-val_i:3+-val_i:4+-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:29+-val_i:32+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:41&facet=true&wt=xml}
 hits=21 status=0 QTime=1 
   [junit4]   2> 227734 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:2+-val_i:4+-val_i:7+-val_i:9+-val_i:10+-val_i:12+-val_i:13+-val_i:15+-val_i:17+-val_i:18+-val_i:19+-val_i:22+-val_i:23+-val_i:25+-val_i:27+-val_i:28+-val_i:37+-val_i:38+-val_i:40+-val_i:41+-val_i:42&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:2+-val_i:4+-val_i:7+-val_i:10+-val_i:11+-val_i:12+-val_i:13+-val_i:14+-val_i:16+-val_i:19+-val_i:21+-val_i:22+-val_i:23+-val_i:24+-val_i:29+-val_i:30+-val_i:35+-val_i:38+-val_i:39+-val_i:40+-val_i:41+-val_i:42&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00013+TO+00042]"&fq={!+cost%3D13}-_query_:"val_i:[00036+TO+00043}"&wt=xml}
 hits=4 status=0 QTime=1 
   [junit4]   2> 227737 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cache%3Dtrue+tag%3Dt}val_s:{00028+TO+00066]&fq={!frange+v%3Dval_i+l%3D7+u%3D26+cache%3Dfalse+tag%3Dt}&fq={!frange+v%3Dval_i+l%3D34+u%3D42}&fq=-_query_:"val_i:[00004+TO+00043}"&fq={!+cache%3Dtrue+tag%3Dt}val_i:[00038+TO+00076}&wt=xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 227739 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D27}+val_i:1+val_i:3+val_i:5+val_i:6+val_i:8+val_i:9+val_i:10+val_i:11+val_i:14+val_i:15+val_i:17+val_i:18+val_i:19+val_i:21+val_i:23+val_i:26+val_i:29+val_i:32+val_i:34+val_i:35+val_i:37+val_i:39+val_i:40+val_i:41+val_i:42&q={!frange+v%3Dval_i+l%3D30+u%3D51+cache%3Dfalse}&fq={!frange+v%3Dval_i+l%3D23+u%3D45}&fq={!frange+v%3Dval_i+l%3D24+u%3D53+cache%3Dfalse+cost%3D179}&fq={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:5+-val_i:6+-val_i:8+-val_i:10+-val_i:12+-val_i:13+-val_i:14+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:19+-val_i:20+-val_i:21+-val_i:22+-val_i:23+-val_i:26+-val_i:27+-val_i:28+-val_i:29+-val_i:30+-val_i:32+-val_i:33+-val_i:36+-val_i:37+-val_i:38+-val_i:40+-val_i:41&facet=true&wt=xml}
 hits=5 status=0 QTime=2 
   [junit4]   2> 227741 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=-_query_:"val_s:[00038+TO+00042]"&fq={!cache%3Dfalse+cost%3D31+tag%3Dt}+val_i:1+val_i:2+val_i:5+val_i:8+val_i:9+val_i:10+val_i:12+val_i:13+val_i:14+val_i:16+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:29+val_i:32+val_i:35+val_i:37+val_i:42&wt=xml}
 hits=22 status=0 QTime=1 
   [junit4]   2> 227741 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cache%3Dfalse+tag%3Dt}-_query_:"{!frange+v%3Dval_i+l%3D5+u%3D42}"&wt=xml}
 hits=5 status=0 QTime=0 
   [junit4]   2> 227743 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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%3D152}-_query_:"val_i:[00040+TO+00042]"&q={!cache%3Dtrue+tag%3Dt}+-val_i:0+-val_i:1+-val_i:6+-val_i:7+-val_i:9+-val_i:10+-val_i:14+-val_i:23+-val_i:24+-val_i:26+-val_i:29+-val_i:30+-val_i:31+-val_i:33+-val_i:37+-val_i:38+-val_i:42&fq={!+cost%3D27}id:RAND1499915790+OR+val_i:{00008+TO+00038}&facet=true&wt=xml}
 hits=18 status=0 QTime=1 
   [junit4]   2> 227745 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dtrue}+-val_i:0+-val_i:3+-val_i:4+-val_i:5+-val_i:7+-val_i:11+-val_i:12+-val_i:13+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:31+-val_i:33+-val_i:34+-val_i:36+-val_i:37+-val_i:39&group.main=true&group.cache.percent=100&fq=-_query_:"{!frange+v%3Dval_i+l%3D18+u%3D42}"&wt=xml&group.field=id&group=true}
 hits=10 status=0 QTime=1 
   [junit4]   2> 227746 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse}+-val_i:0+-val_i:9+-val_i:11+-val_i:13+-val_i:14+-val_i:16+-val_i:19+-val_i:26+-val_i:28+-val_i:30+-val_i:31+-val_i:34+-val_i:36+-val_i:40+-val_i:42&group.main=true&group.cache.percent=100&fq=-_query_:"val_i:{00035+TO+00043}"&wt=xml&group.field=id&group=true}
 hits=24 status=0 QTime=1 
   [junit4]   2> 227748 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:5+-val_i:6+-val_i:10+-val_i:12+-val_i:13+-val_i:16+-val_i:17+-val_i:18+-val_i:21+-val_i:24+-val_i:27+-val_i:30+-val_i:32+-val_i:34+-val_i:35+-val_i:36+-val_i:37+-val_i:38+-val_i:39+-val_i:40&fq=-_query_:"val_i:[00026+TO+00042]"&wt=xml}
 hits=14 status=0 QTime=0 
   [junit4]   2> 227748 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=+-val_i:0+-val_i:2+-val_i:3+-val_i:5+-val_i:7+-val_i:8+-val_i:9+-val_i:10+-val_i:13+-val_i:15+-val_i:16+-val_i:17+-val_i:18+-val_i:21+-val_i:22+-val_i:24+-val_i:27+-val_i:29+-val_i:30+-val_i:32+-val_i:37+-val_i:38+-val_i:39&wt=xml}
 hits=20 status=0 QTime=0 
   [junit4]   2> 227750 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:[00025+TO+00060]&q={!+tag%3Dt}val_i:{00030+TO+00041}+OR+id:RAND-1780858673&fq={!frange+v%3Dval_i+l%3D3+u%3D28+cost%3D3+tag%3Dt}&fq={!+cache%3Dtrue+tag%3Dt}-_query_:"val_s:{00014+TO+00043}"&facet=true&wt=xml}
 hits=0 status=0 QTime=1 
   [junit4]   2> 227753 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
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:1+val_i:4+val_i:6+val_i:7+val_i:8+val_i:10+val_i:11+val_i:18+val_i:21+val_i:23+val_i:24+val_i:26+val_i:28+val_i:29+val_i:30+val_i:38+val_i:42&q={!+cost%3D93}-_query_:"val_s:{00035+TO+00043}"&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:4+-val_i:8+-val_i:9+-val_i:10+-val_i:11+-val_i:12+-val_i:14+-val_i:21+-val_i:23+-val_i:24+-val_i:26+-val_i:27+-val_i:32+-val_i:33+-val_i:34+-val_i:35+-val_i:36+-val_i:38+-val_i:42&fq={!cache%3Dfalse+tag%3Dt}+-val_i:5+-val_i:8+-val_i:9+-val_i:12+-val_i:14+-val_i:16+-val_i:17+-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:34+-val_i:38+-val_i:39&fq={!cache%3Dfalse+cost%3D107+tag%3Dt}+val_i:2+val_i:3+val_i:5+val_i:6+val_i:8+val_i:9+val_i:10+val_i:13+val_i:17+val_i:21+val_i:25+val_i:27+val_i:28+val_i:30+val_i:31+val_i:34+val_i:39+val_i:40+val_i:42&facet=true&wt=xml}
 hits=7 status=0 QTime=2 
   [junit4]   2> 227755 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={facet.query=*:*&facet.query={!key%3DmultiSelect+ex%3Dt}*:*&facet.query={!key%3DfacetQuery++cost%3D121}-_query_:"{!frange+v%3Dval_i+l%3D41+u%3D42}"&q=-_query_:"val_s:[00026+TO+00043}"&fq=-_query_:"{!frange+v%3Dval_i+l%3D42+u%3D42}"&facet=true&wt=xml}
 hits=26 status=0 QTime=1 
   [junit4]   2> 227756 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!+cache%3Dfalse+cost%3D40+tag%3Dt}val_s:{00010+TO+00050]&wt=xml} 
hits=32 status=0 QTime=0 
   [junit4]   2> 227759 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!cache%3Dfalse+cost%3D35+tag%3Dt}+-val_i:2+-val_i:3+-val_i:4+-val_i:5+-val_i:6+-val_i:7+-val_i:9+-val_i:10+-val_i:12+-val_i:16+-val_i:17+-val_i:18+-val_i:20+-val_i:23+-val_i:24+-val_i:25+-val_i:26+-val_i:31+-val_i:32+-val_i:38+-val_i:41&group.main=true&fq={!frange+v%3Dval_i+l%3D8+u%3D17}&fq={!cache%3Dtrue+tag%3Dt}+-val_i:1+-val_i:2+-val_i:3+-val_i:5+-val_i:6+-val_i:8+-val_i:12+-val_i:15+-val_i:17+-val_i:19+-val_i:22+-val_i:23+-val_i:24+-val_i:25+-val_i:28+-val_i:29+-val_i:31+-val_i:32+-val_i:34+-val_i:36+-val_i:37+-val_i:38+-val_i:40+-val_i:41&fq={!+cost%3D48}-_query_:"{!frange+v%3Dval_i+l%3D27+u%3D42}"&fq={!cache%3Dfalse}+val_i:2+val_i:4+val_i:5+val_i:6+val_i:8+val_i:10+val_i:11+val_i:16+val_i:17+val_i:18+val_i:21+val_i:23+val_i:24+val_i:25+val_i:28+val_i:29+val_i:30+val_i:31+val_i:33+val_i:34+val_i:36+val_i:37+val_i:41+val_i:42&wt=xml&group.field=id&group=true}
 hits=1 status=0 QTime=3 
   [junit4]   2> 227762 INFO  
(TEST-TestFiltering.testRandomFiltering-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection

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

ckFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_1g,generation=52}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@b238a4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1811aba),segFN=segments_1h,generation=53}
   [junit4]   2> 233295 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.SolrCore newest commit generation = 53
   [junit4]   2> 233296 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@dc0f06[collection1] main
   [junit4]   2> 233296 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 233297 INFO  (searcherExecutor-822-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@dc0f06[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1f(6.0.0):c4)))}
   [junit4]   2> 233297 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 4
   [junit4]   2> 233298 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D3+cache%3Dfalse+cost%3D100}val_i&wt=xml} 
hits=2 status=0 QTime=0 
   [junit4]   2> 233298 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D3+cache%3Dfalse+cost%3D100}val_i&wt=xml} 
hits=2 status=0 QTime=0 
   [junit4]   2> 233298 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
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> 233299 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&fq={!frange+l%3D2+u%3D5+cache%3Dfalse}val_i&wt=xml} hits=3 
status=0 QTime=0 
   [junit4]   2> 233299 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D6+cache%3Dfalse+cost%3D100}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0 
   [junit4]   2> 233300 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D6+cache%3Dfalse+cost%3D100}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0 
   [junit4]   2> 233300 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
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> 233300 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&facet.field=id&fq={!frange+l%3D2+u%3D8+cache%3Dfalse}val_i&facet=true&wt=xml}
 hits=3 status=0 QTime=0 
   [junit4]   2> 233301 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
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> 233301 INFO  
(TEST-TestFiltering.testCaching-seed#[20925532CE885922]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testCaching
   [junit4]   2> 233301 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 233301 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=9196432
   [junit4]   2> 233302 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2b7ab9
   [junit4]   2> 233302 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=53,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=433,cumulative_deletesById=0,cumulative_deletesByQuery=7,cumulative_errors=0}
   [junit4]   2> 233302 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 233302 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing 
IndexWriter
   [junit4]   2> 233302 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 233302 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 233317 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories 
currently being tracked
   [junit4]   2> 233317 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001;done=false>>]
   [junit4]   2> 233318 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001
   [junit4]   2> 233318 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 233318 INFO  
(SUITE-TestFiltering-seed#[20925532CE885922]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestFiltering_20925532CE885922-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): 
{val_i=PostingsFormat(name=Direct), val_s=PostingsFormat(name=LuceneFixedGap), 
id=PostingsFormat(name=Direct)}, docValues:{}, sim=ClassicSimilarity, 
locale=ar_LY, timezone=Etc/GMT+7
   [junit4]   2> NOTE: Linux 3.19.0-28-generic i386/Oracle Corporation 1.8.0_60 
(32-bit)/cpus=12,threads=1,free=174575352,total=311689216
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestWordDelimiterFilterFactory, DistributedFacetPivotWhiteBoxTest, 
SolrCloudExampleTest, MultiTermTest, TestBM25SimilarityFactory, TestRTGBase, 
ChangedSchemaMergeTest, AtomicUpdatesTest, MBeansHandlerTest, TestQueryTypes, 
MergeStrategyTest, JavabinLoaderTest, TestJsonRequest, SuggestComponentTest, 
TestQueryUtils, UniqFieldsUpdateProcessorFactoryTest, BlockCacheTest, 
TestFileDictionaryLookup, DocValuesMissingTest, TestMaxScoreQueryParser, 
TestSchemaVersionResource, TestQuerySenderListener, 
TriLevelCompositeIdRoutingTest, TestReqParamsAPI, NotRequiredUniqueKeyTest, 
ConvertedLegacyTest, DocExpirationUpdateProcessorFactoryTest, 
DistribDocExpirationUpdateProcessorTest, ShardRoutingCustomTest, 
DistributedFacetPivotLongTailTest, SimpleMLTQParserTest, 
TestObjectReleaseTracker, AnalysisErrorHandlingTest, HdfsLockFactoryTest, 
TestCrossCoreJoin, MoreLikeThisHandlerTest, PreAnalyzedFieldTest, 
TestConfigSetsAPIExclusivity, TestBlendedInfixSuggestions, CleanupOldIndexTest, 
PreAnalyzedUpdateProcessorTest, HdfsRecoveryZkTest, URLClassifyProcessorTest, 
HdfsNNFailoverTest, TestScoreJoinQPScore, TestReloadDeadlock, TestFiltering]
   [junit4] Completed [117/546] on J1 in 6.14s, 3 tests, 1 failure <<< FAILURES!

[...truncated 1353 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:775: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:719: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:514: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1432: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:987: 
There were test failures: 546 suites (8 ignored), 2145 tests, 1 failure, 71 
ignored (35 assumptions) [seed: 20925532CE885922]

Total time: 46 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to