Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/15/
Java: 64bit/jdk1.7.0_80 -XX:-UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.TestGroupingSearch.testGroupAPI

Error Message:
mismatch: '2'!='5' @ grouped/id:[2 TO 5]/doclist/docs/[2]/id

Stack Trace:
java.lang.RuntimeException: mismatch: '2'!='5' @ grouped/id:[2 TO 
5]/doclist/docs/[2]/id
        at 
__randomizedtesting.SeedInfo.seed([34B2387B3332DFBF:A9170C36A00F2F95]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:842)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:789)
        at 
org.apache.solr.TestGroupingSearch.testGroupAPI(TestGroupingSearch.java:542)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
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:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        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:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
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 11543 lines...]
   [junit4] Suite: org.apache.solr.TestGroupingSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001
   [junit4]   2> 1071272 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 1071273 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1071273 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1071273 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1071273 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 1071273 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1071273 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1071292 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1071306 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 1071335 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1071340 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1071490 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1071491 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1071491 WARN  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (2)
   [junit4]   2> 1071495 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1071496 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr'
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1973607141
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr]
   [junit4]   2> 1071498 WARN  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/lib 
to classpath: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 1071498 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-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> 1071499 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1071499 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1071499 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1071499 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1071499 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1071500 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=false, 
name=collection1, loadOnStartup=true, config=solrconfig.xml, 
schema=schema12.xml, 
dataDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001,
 configSetProperties=configsetprops.json, collection=collection1, shard=shard1}
   [junit4]   2> 1071500 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1071500 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1071500 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr
   [junit4]   2> 1071501 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1071501 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1071518 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1071538 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 1071566 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1071571 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1071724 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1071726 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1071727 WARN  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (2)
   [junit4]   2> 1071733 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1071735 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1071739 INFO  (coreLoadExecutor-3778-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-5.5-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1071739 INFO  (coreLoadExecutor-3778-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1071739 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1071740 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/]
   [junit4]   2> 1071740 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7897fe89
   [junit4]   2> 1071740 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001
   [junit4]   2> 1071740 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/index/
   [junit4]   2> 1071740 WARN  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1071741 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/index
   [junit4]   2> 1071742 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=12, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49195201701196156]
   [junit4]   2> 1071742 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_1,generation=1}
   [junit4]   2> 1071742 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1071742 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-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1071746 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1071747 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1071747 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1071747 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1071750 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1071751 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1071752 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1071754 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1071757 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 1071759 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
mock,/update/json,/update/json/docs,/admin/system,spellCheckWithWordbreak,spellCheckCompRH,spellCheckCompRH_Direct,/admin/file,/schema,/get,/mlt,/admin/threads,/search-facet-def,defaults,dismax,spellCheckWithWordbreak_Direct,/terms,/update/csv,/admin/luke,/search-facet-invariants,/debug/dump,/admin/plugins,/admin/ping,/admin/logging,/admin/segments,/admin/properties,lazy,/config,tvrh,spellCheckCompRH1,/update,mltrh,standard,/replication,/admin/mbeans
   [junit4]   2> 1071761 WARN  (coreLoadExecutor-3778-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> 1071761 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=3, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=76.4990234375, 
floorSegmentMB=0.6337890625, forceMergeDeletesPctAllowed=10.348498240622453, 
segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.2753519343688011
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_1,generation=1}
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@265e9d50[collection1] 
main
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 1071762 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1071763 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1071791 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1071937 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1071937 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1071938 INFO  (coreLoadExecutor-3778-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1071938 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1071939 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1071939 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1071939 INFO  
(searcherExecutor-3779-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@265e9d50[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1071942 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testGroupingGroupedBasedFacetingWithTaggedFilter
   [junit4]   2> 1071942 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1071942 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 1071944 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071944 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_2,generation=2}
   [junit4]   2> 1071944 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1071944 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@2198c282[collection1] main
   [junit4]   2> 1071945 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071946 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2198c282[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1071946 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 2
   [junit4]   2> 1071947 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1071947 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1071948 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1071948 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1071949 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1071949 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071951 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_3,generation=3}
   [junit4]   2> 1071951 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1071951 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@23bad885[collection1] main
   [junit4]   2> 1071952 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071952 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@23bad885[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C5)))}
   [junit4]   2> 1071952 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 2
   [junit4]   2> 1071962 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.query={!ex%3Dchk+key%3DLW1}bday:[2013-01-11T00:00:00Z+TO+2013-01-17T23:59:59Z]&facet.query={!ex%3Dchk+key%3DLM1}bday:[2012-12-18T00:00:00Z+TO+2013-01-17T23:59:59Z]&facet.query={!ex%3Dchk+key%3DLM3}bday:[2012-10-18T00:00:00Z+TO+2013-01-17T23:59:59Z]&group.truncate=true&rows=1&fl=id&sort=cat_sI+asc&group=true&group.sort=bday+desc&q=*:*&fq={!tag%3Dchk}bday:[2012-12-18T00:00:00Z+TO+2013-01-17T23:59:59Z]&group.field=cat_sI&wt=xml&facet=true}
 hits=2 status=0 QTime=8
   [junit4]   2> 1071962 INFO  
(TEST-TestGroupingSearch.testGroupingGroupedBasedFacetingWithTaggedFilter-seed#[34B2387B3332DFBF])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testGroupingGroupedBasedFacetingWithTaggedFilter
   [junit4]   2> 1071965 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testGroupAPI
   [junit4]   2> 1071965 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1071965 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 1071966 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071966 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_4,generation=4}
   [junit4]   2> 1071966 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 4
   [junit4]   2> 1071966 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@615e557d[collection1] main
   [junit4]   2> 1071967 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071967 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@615e557d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1071968 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1071969 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1]} 0 0
   [junit4]   2> 1071970 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[2]} 0 0
   [junit4]   2> 1071970 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[3]} 0 0
   [junit4]   2> 1071971 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071972 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_5,generation=5}
   [junit4]   2> 1071972 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 5
   [junit4]   2> 1071972 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1620276e[collection1] main
   [junit4]   2> 1071973 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071973 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1620276e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):C3)))}
   [junit4]   2> 1071974 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1071974 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[4]} 0 0
   [junit4]   2> 1071975 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[5]} 0 0
   [junit4]   2> 1071975 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[6]} 0 0
   [junit4]   2> 1071976 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[7]} 0 0
   [junit4]   2> 1071976 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071978 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_6,generation=6}
   [junit4]   2> 1071978 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 6
   [junit4]   2> 1071978 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@69ead050[collection1] main
   [junit4]   2> 1071979 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071979 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@69ead050[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):C3)
 Uninverting(_2(5.5.0):C4)))}
   [junit4]   2> 1071980 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1071981 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8]} 0 0
   [junit4]   2> 1071982 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[9]} 0 0
   [junit4]   2> 1071982 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071984 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_7,generation=7}
   [junit4]   2> 1071984 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 7
   [junit4]   2> 1071985 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@7b6b947[collection1] main
   [junit4]   2> 1071985 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071986 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7b6b947[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):C3)
 Uninverting(_2(5.5.0):C4) Uninverting(_3(5.5.0):C2)))}
   [junit4]   2> 1071986 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1071987 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[10]} 0 0
   [junit4]   2> 1071988 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1071989 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_7,generation=7}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_8,generation=8}
   [junit4]   2> 1071989 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 8
   [junit4]   2> 1071990 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@65c3564b[collection1] main
   [junit4]   2> 1071990 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1071991 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@65c3564b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(5.5.0):c9)
 Uninverting(_5(5.5.0):C1)))}
   [junit4]   2> 1071991 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1071992 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml} 
hits=10 status=0 QTime=0
   [junit4]   2> 1071994 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={fl=id&group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071994 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={fl=id&group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&fq=foo_i:2&wt=xml}
 hits=3 status=0 QTime=0
   [junit4]   2> 1071995 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=2&fl=id&group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071995 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=1&fl=id&group=true&start=1&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071995 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=1&fl=id&group=true&start=100&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071996 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=2&fl=id&group=true&group.limit=3&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071996 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=2&fl=id&group=true&group.offset=1&group.limit=3&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071996 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=2&fl=id&group=true&group.offset=10&group.limit=3&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071997 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=2&fl=id,score&group=true&group.limit=2&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&indent=off&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071998 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=2&fl=id&group=true&group.func=add(foo_i,foo_i)&q={!func}foo2_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071998 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={facet.field=foo_i&fl=id&group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml&facet=true}
 hits=10 status=0 QTime=0
   [junit4]   2> 1071999 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={hl.fl=foo_i&fl=id&group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml&hl=true}
 hits=10 status=0 QTime=0
   [junit4]   2> 1072000 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={debugQuery=true&fl=id&group=true&q={!func}foo2_i&group.field=foo_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=0
   [junit4]   2> 1072003 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={fl=id&group.query=id:[2+TO+5]&group=true&group.limit=3&q={!func}foo2_i&fq=foo_i:[*+TO+*]&wt=xml}
 hits=10 status=0 QTime=2
   [junit4]   2> 1072003 ERROR 
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: '2'!='5' @ 
grouped/id:[2 TO 5]/doclist/docs/[2]/id
   [junit4]   2>  expected =/grouped=={'id:[2 TO 
5]':{'matches':10,'doclist':{'numFound':4,'start':0,'docs':[{'id':'3'},{'id':'4'},{'id':'2'}]}}}
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":2},
   [junit4]   2>   "grouped":{
   [junit4]   2>     "id:[2 TO 5]":{
   [junit4]   2>       "matches":10,
   [junit4]   2>       "doclist":{"numFound":4,"start":0,"docs":[
   [junit4]   2>           {
   [junit4]   2>             "id":"3"},
   [junit4]   2>           {
   [junit4]   2>             "id":"4"},
   [junit4]   2>           {
   [junit4]   2>             "id":"5"}]
   [junit4]   2>       }}}}
   [junit4]   2> 
   [junit4]   2>  request = 
fl=id&group.query=id:[2+TO+5]&group=true&group.limit=3&q={!func}foo2_i&fq=foo_i:[*+TO+*]&wt=xml
   [junit4]   2> 1072003 INFO  
(TEST-TestGroupingSearch.testGroupAPI-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testGroupAPI
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestGroupingSearch 
-Dtests.method=testGroupAPI -Dtests.seed=34B2387B3332DFBF -Dtests.multiplier=3 
-Dtests.slow=true -Dtests.locale=ga-IE -Dtests.timezone=America/Curacao 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.04s J0 | TestGroupingSearch.testGroupAPI <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: '2'!='5' @ 
grouped/id:[2 TO 5]/doclist/docs/[2]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([34B2387B3332DFBF:A9170C36A00F2F95]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:842)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:789)
   [junit4]    >        at 
org.apache.solr.TestGroupingSearch.testGroupAPI(TestGroupingSearch.java:542)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1072007 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testGroupingGroupSortingScore_basic
   [junit4]   2> 1072007 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1072007 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 1072008 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072008 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_8,generation=8}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_9,generation=9}
   [junit4]   2> 1072008 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 9
   [junit4]   2> 1072009 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@2133ab4a[collection1] main
   [junit4]   2> 1072009 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072010 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2133ab4a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1072010 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 1
   [junit4]   2> 1072011 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1072012 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1072012 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1072013 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[4]} 0 0
   [junit4]   2> 1072013 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 1072014 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072015 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_9,generation=9}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_a,generation=10}
   [junit4]   2> 1072015 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 10
   [junit4]   2> 1072016 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@654b8cc5[collection1] main
   [junit4]   2> 1072016 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072017 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@654b8cc5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.5.0):C5)))}
   [junit4]   2> 1072018 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 3
   [junit4]   2> 1072019 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={group=true&q=title:title&group.field=name&wt=xml} hits=5 status=0 
QTime=0
   [junit4]   2> 1072023 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={group=true&q=title:title&group.field=group_i&wt=xml} hits=5 status=0 
QTime=0
   [junit4]   2> 1072033 INFO  
(TEST-TestGroupingSearch.testGroupingGroupSortingScore_basic-seed#[34B2387B3332DFBF])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testGroupingGroupSortingScore_basic
   [junit4]   2> 1072036 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testGroupingSimpleFormatArrayIndexOutOfBoundsException
   [junit4]   2> 1072036 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1072036 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 1072037 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072037 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_a,generation=10}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_b,generation=11}
   [junit4]   2> 1072037 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 11
   [junit4]   2> 1072037 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@45ce7454[collection1] main
   [junit4]   2> 1072038 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072038 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@45ce7454[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1072038 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 1
   [junit4]   2> 1072039 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 1072040 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 1072040 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 1072041 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072042 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_b,generation=11}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_c,generation=12}
   [junit4]   2> 1072042 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 12
   [junit4]   2> 1072043 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@5091cb30[collection1] main
   [junit4]   2> 1072043 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072044 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5091cb30[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(5.5.0):C3)))}
   [junit4]   2> 1072044 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 2
   [junit4]   2> 1072045 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={group=true&group.main=true&start=1&q=*:*&group.field=id&wt=xml} hits=3 
status=0 QTime=0
   [junit4]   2> 1072045 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=1&group=true&group.main=true&start=1&q=*:*&group.field=id&wt=xml} 
hits=3 status=0 QTime=0
   [junit4]   2> 1072045 INFO  
(TEST-TestGroupingSearch.testGroupingSimpleFormatArrayIndexOutOfBoundsException-seed#[34B2387B3332DFBF])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testGroupingSimpleFormatArrayIndexOutOfBoundsException
   [junit4]   2> 1072048 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testRandomGrouping
   [junit4]   2> 1072048 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1072048 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 1072049 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072049 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_c,generation=12}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_d,generation=13}
   [junit4]   2> 1072049 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 13
   [junit4]   2> 1072049 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@296e6944[collection1] main
   [junit4]   2> 1072049 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072050 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@296e6944[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1072050 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1072051 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1072051 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 1072052 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[SCOC]} 0 0
   [junit4]   2> 1072052 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[CWLU]} 0 0
   [junit4]   2> 1072052 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[LIDT]} 0 0
   [junit4]   2> 1072053 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[WSFV]} 0 0
   [junit4]   2> 1072053 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[WYDR]} 0 0
   [junit4]   2> 1072053 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[OGCM]} 0 0
   [junit4]   2> 1072053 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[PLZG]} 0 0
   [junit4]   2> 1072053 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[KIPV]} 0 0
   [junit4]   2> 1072054 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[LGNY]} 0 0
   [junit4]   2> 1072054 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[VWHL]} 0 0
   [junit4]   2> 1072054 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[MQJS]} 0 0
   [junit4]   2> 1072054 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072055 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_d,generation=13}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_e,generation=14}
   [junit4]   2> 1072055 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 14
   [junit4]   2> 1072056 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@55601312[collection1] main
   [junit4]   2> 1072056 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072057 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55601312[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(5.5.0):C11)))}
   [junit4]   2> 1072057 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1072058 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[CFEN]} 0 0
   [junit4]   2> 1072058 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[HWZU]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[QXHS]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[SUAM]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[KBAN]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ZCJB]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[YQZH]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[VGOY]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[AQBQ]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[AQBQ]} 0 0
   [junit4]   2> 1072059 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[WTET]} 0 0
   [junit4]   2> 1072060 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[YIGA]} 0 0
   [junit4]   2> 1072060 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[XSFF]} 0 0
   [junit4]   2> 1072060 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[UQMS]} 0 0
   [junit4]   2> 1072060 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072062 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_e,generation=14}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_f,generation=15}
   [junit4]   2> 1072062 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 15
   [junit4]   2> 1072062 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@77093321[collection1] main
   [junit4]   2> 1072062 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072063 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@77093321[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(5.5.0):C11)
 Uninverting(_9(5.5.0):C14/1:delGen=1)))}
   [junit4]   2> 1072063 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1072065 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[MDMP]} 0 0
   [junit4]   2> 1072065 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[MDMP]} 0 0
   [junit4]   2> 1072065 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[IXHQ]} 0 0
   [junit4]   2> 1072065 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[CGJW]} 0 0
   [junit4]   2> 1072065 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[RXVP]} 0 0
   [junit4]   2> 1072065 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[NBLM]} 0 0
   [junit4]   2> 1072066 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[PACR]} 0 0
   [junit4]   2> 1072066 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[KJZN]} 0 0
   [junit4]   2> 1072066 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072068 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_f,generation=15}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_g,generation=16}
   [junit4]   2> 1072068 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 16
   [junit4]   2> 1072069 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@7d81bd73[collection1] main
   [junit4]   2> 1072069 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072070 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d81bd73[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(5.5.0):C11)
 Uninverting(_9(5.5.0):C14/1:delGen=1) Uninverting(_a(5.5.0):C8/1:delGen=1)))}
   [junit4]   2> 1072070 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1072071 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ZTNC]} 0 0
   [junit4]   2> 1072071 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[TEWC]} 0 0
   [junit4]   2> 1072071 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[UICB]} 0 0
   [junit4]   2> 1072071 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[MLWD]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[EDKT]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[HRVC]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[SRAG]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[RWRY]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[RWRY]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[AANM]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[VJYK]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[TVXY]} 0 0
   [junit4]   2> 1072072 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ZFRK]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[JUVK]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[AVFN]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[XTVC]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ZMKJ]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[BXLR]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[AXFW]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[SFPW]} 0 0
   [junit4]   2> 1072073 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1072076 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_g,generation=16}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_h,generation=17}
   [junit4]   2> 1072076 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 17
   [junit4]   2> 1072076 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@3a58766d[collection1] main
   [junit4]   2> 1072077 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072078 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3a58766d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(5.5.0):C31)
 Uninverting(_c(5.5.0):C20/1:delGen=1)))}
   [junit4]   2> 1072078 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1072079 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[CFIU]} 0 0
   [junit4]   2> 1072079 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[SAFO]} 0 0
   [junit4]   2> 1072079 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[AUFZ]} 0 0
   [junit4]   2> 1072079 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[KISB]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[GGAU]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[GHPI]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[EBDK]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[JFMX]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[QARM]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ZYFW]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[DAEH]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[WJZF]} 0 0
   [junit4]   2> 1072080 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ERRH]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[DWJE]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[LQVG]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[ZEIZ]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[VSXL]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[WBHJ]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[HEBF]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={wt=json&indent=true}{add=[FQYK]} 0 0
   [junit4]   2> 1072081 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1072084 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@654eb9e1[collection1] main
   [junit4]   2> 1072084 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1072085 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@654eb9e1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(5.5.0):C31)
 Uninverting(_c(5.5.0):C20/1:delGen=1) Uninverting(_d(5.5.0):C20)))}
   [junit4]   2> 1072086 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1072087 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={rows=140&sort=_docid_+asc&fl=id&q=*:*&indent=true&wt=json} hits=70 
status=0 QTime=0
   [junit4]   2> 1072095 WARN  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.Grouping The grouping cache is active, but not used because it exceeded 
the max cache limit of 12 percent
   [junit4]   2> 1072095 WARN  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.s.Grouping Please increase cache size or disable group caching.
   [junit4]   2> 1072096 INFO  
(TEST-TestGroupingSearch.testRandomGrouping-seed#[34B2387B3332DFBF]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={group.truncate=true&facet.field=foo_s1&group=true&facet.mincount=1&group.field=small_s1&group.cache.percent=12&facet.method=fcs&echoParams=all&rows=7&facet.sort=in

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

  2>    commit{dir=MockDirectoryWrapper(RAMDirectory@7f1a71d0 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21264fe1),segFN=segments_58,generation=188}
   [junit4]   2> 1079184 INFO  
(TEST-TestGroupingSearch.testGroupingWithTimeAllowed-seed#[34B2387B3332DFBF]) [ 
   ] o.a.s.c.SolrDeletionPolicy newest commit generation = 188
   [junit4]   2> 1079184 INFO  
(TEST-TestGroupingSearch.testGroupingWithTimeAllowed-seed#[34B2387B3332DFBF]) [ 
   ] o.a.s.s.SolrIndexSearcher Opening Searcher@20b0edc9[collection1] main
   [junit4]   2> 1079184 INFO  
(TEST-TestGroupingSearch.testGroupingWithTimeAllowed-seed#[34B2387B3332DFBF]) [ 
   ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1079185 INFO  (searcherExecutor-3779-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@20b0edc9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7j(5.5.0):C5)))}
   [junit4]   2> 1079185 INFO  
(TEST-TestGroupingSearch.testGroupingWithTimeAllowed-seed#[34B2387B3332DFBF]) [ 
   ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1079186 INFO  
(TEST-TestGroupingSearch.testGroupingWithTimeAllowed-seed#[34B2387B3332DFBF]) [ 
   ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={group.query=id:1&group.query=id:2&group=true&timeAllowed=1&q=*:*&wt=xml}
 hits=5 status=0 QTime=0
   [junit4]   2> 1079186 INFO  
(TEST-TestGroupingSearch.testGroupingWithTimeAllowed-seed#[34B2387B3332DFBF]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Ending testGroupingWithTimeAllowed
   [junit4]   2> 1079187 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1079187 INFO  
(SUITE-TestGroupingSearch-seed#[34B2387B3332DFBF]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1973607141
   [junit4]   2> 1079187 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@270ff0fd
   [junit4]   2> 1079187 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 closing 
DirectUpdateHandler2{commits=221,autocommits=0,soft 
autocommits=0,optimizes=3,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1849,cumulative_deletesById=0,cumulative_deletesByQuery=57,cumulative_errors=0}
   [junit4]   2> 1079187 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 1079187 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 
0 - closing IndexWriter
   [junit4]   2> 1079187 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 1079188 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1079196 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 
directories currently being tracked
   [junit4]   2> 1079196 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001;done=false>>]
   [junit4]   2> 1079196 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001
   [junit4]   2> 1079196 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 1079196 INFO  (coreCloseExecutor-3783-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.TestGroupingSearch_34B2387B3332DFBF-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): 
{foo2_i=BlockTreeOrds(blocksize=128), 
small_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), cat_sI=FSTOrd50, 
name=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
title_stemmed=PostingsFormat(name=LuceneFixedGap), 
foo_i=PostingsFormat(name=LuceneFixedGap), 
id=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
bday=BlockTreeOrds(blocksize=128), foo_s1=FSTOrd50, score_f=FSTOrd50, 
group_i=FSTOrd50, value1_i=BlockTreeOrds(blocksize=128), 
store_rpt=PostingsFormat(name=LuceneFixedGap), value3_s1=FSTOrd50, 
store_0_coordinate=FSTOrd50, nullfirst=FSTOrd50, 
value4_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), value2_i=FSTOrd50, 
store_1_coordinate=PostingsFormat(name=LuceneFixedGap), 
value1_s1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
weight=PostingsFormat(name=LuceneFixedGap), text=FSTOrd50, 
title=PostingsFormat(name=LuceneFixedGap), 
small_s1=BlockTreeOrds(blocksize=128), 
title_lettertok=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, 
docValues:{}, sim=DefaultSimilarity, locale=ga-IE, timezone=America/Curacao
   [junit4]   2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 
1.7.0_80 (64-bit)/cpus=12,threads=1,free=225233384,total=533725184
   [junit4]   2> NOTE: All tests run in this JVM: 
[SegmentsInfoRequestHandlerTest, TestIBSimilarityFactory, 
CoreMergeIndexesAdminHandlerTest, TestSerializedLuceneMatchVersion, 
TestDistribDocBasedVersion, TestRandomDVFaceting, TestStandardQParsers, 
SystemInfoHandlerTest, TestCSVLoader, UUIDUpdateProcessorFallbackTest, 
ExitableDirectoryReaderTest, TestValueSourceCache, ActionThrottleTest, 
CleanupOldIndexTest, DistributedFacetPivotSmallTest, ExplicitHLLTest, 
BasicAuthIntegrationTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
TestMiniSolrCloudClusterBase, TestRealTimeGet, 
SignatureUpdateProcessorFactoryTest, TestPhraseSuggestions, TestCrossCoreJoin, 
TestChildDocTransformer, StandardRequestHandlerTest, 
TestRandomCollapseQParserPlugin, RestartWhileUpdatingTest, TestConfig, 
TestSimpleQParserPlugin, TestFoldingMultitermQuery, AlternateDirectoryTest, 
TestLRUCache, TestRawTransformer, BufferStoreTest, CloudMLTQParserTest, 
TestRandomFaceting, CursorPagingTest, TestManagedResource, TestBulkSchemaAPI, 
SolrRequestParserTest, SyncSliceTest, TestSchemaResource, 
SpatialHeatmapFacetsTest, BasicZkTest, IndexSchemaTest, 
RAMDirectoryFactoryTest, CacheHeaderTest, TestRequestForwarding, 
ReturnFieldsTest, TestLeaderElectionZkExpiry, SaslZkACLProviderTest, 
TestLMDirichletSimilarityFactory, XsltUpdateRequestHandlerTest, JsonLoaderTest, 
DistributedQueryComponentOptimizationTest, TestDefaultSearchFieldResource, 
TestDocSet, DefaultValueUpdateProcessorTest, TestFaceting, RequiredFieldsTest, 
TestFastOutputStream, ExternalFileFieldSortTest, TestSolr4Spatial, 
DateFieldTest, TestStressLucene, TestMergePolicyConfig, 
HdfsBasicDistributedZk2Test, SolrXmlInZkTest, CollectionStateFormat2Test, 
TestUniqueKeyFieldResource, ConnectionReuseTest, TestRTGBase, 
ZkStateReaderTest, TestCloudPivotFacet, ParsingFieldUpdateProcessorsTest, 
ResourceLoaderTest, EchoParamsTest, CopyFieldTest, 
TestConfigSetsAPIExclusivity, TestReplicationHandler, 
AnalyticsMergeStrategyTest, TestBinaryField, PreAnalyzedFieldTest, 
TestOrdValues, TestFieldCollectionResource, IndexSchemaRuntimeFieldTest, 
TestAtomicUpdateErrorCases, TestTrackingShardHandlerFactory, 
TestReloadAndDeleteDocs, WrapperMergePolicyFactoryTest, PrimUtilsTest, 
SolrCoreTest, AsyncCallRequestStatusResponseTest, BasicDistributedZkTest, 
ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, 
LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, 
DistributedSpellCheckComponentTest, TestStressVersions, HardAutoCommitTest, 
TestRangeQuery, TestGroupingSearch]
   [junit4] Completed [305/566 (1!)] on J0 in 7.94s, 13 tests, 1 error <<< 
FAILURES!

[...truncated 818 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error 
occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build.xml:233: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/common-build.xml:534: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: 
There were test failures: 566 suites (7 ignored), 2275 tests, 1 error, 84 
ignored (39 assumptions) [seed: 34B2387B3332DFBF]

Total time: 57 minutes 34 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