You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/02/15 23:18:29 UTC
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 15 -
Still Failing!
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
RE: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16 - Still Failing!
Posted by Uwe Schindler <uw...@thetaphi.de>.
Hi,
I committed this after review of Robert. I think we are safe now on Lucene 5. It only enables unmapping if everything is looking as expected.
Maybe wait for a few Jenkins runs.
Uwe
-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
<http://www.thetaphi.de/> http://www.thetaphi.de
eMail: uwe@thetaphi.de
From: Michael McCandless [mailto:lucene@mikemccandless.com]
Sent: Tuesday, February 16, 2016 3:28 PM
To: dev@lucene.apache.org
Subject: Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16 - Still Failing!
OK I will wait...
On Tuesday, February 16, 2016, Uwe Schindler <uwe@thetaphi.de <ma...@thetaphi.de> > wrote:
Hi Mike,
after discussing with Robert, we improved the 5.x chacks, so we fail early, also on non-compatible JVMs like J9, JRockit, Zing, whatever.
The new patch does not disable Java 9 at all, it just checks more pedantic.
I want a review of Robert on it: https://issues.apache.org/jira/browse/LUCENE-6989
Can you wait with respin?
Uwe
-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
http://www.thetaphi.de
eMail: uwe@thetaphi.de <javascript:;>
> -----Original Message-----
> From: Michael McCandless [mailto:lucene@mikemccandless.com <javascript:;> ]
> Sent: Tuesday, February 16, 2016 12:40 AM
> To: Lucene/Solr dev <dev@lucene.apache.org <javascript:;> >
> Subject: Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16 -
> Still Failing!
>
> I'll fix, test bug.
>
> Uwe, are you done pushing the mmap fixes to 5.5?
>
> Mike McCandless
>
> http://blog.mikemccandless.com
>
>
> On Mon, Feb 15, 2016 at 6:37 PM, Policeman Jenkins Server
> <jenkins@thetaphi.de <javascript:;> > wrote:
> > Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/16/
> > Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC
> >
> > 1 tests failed.
> > FAILED:
> junit.framework.TestSuite.org.apache.lucene.index.TestUpgradeIndexMerg
> ePolicy
> >
> > Error Message:
> > Resource in scope SUITE failed to close. Resource was registered from
> thread Thread[id=273, name=TEST-
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> TestUpgradeIndexMergePolicy], registration stack trace below.
> >
> > Stack Trace:
> > com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in
> scope SUITE failed to close. Resource was registered from thread
> Thread[id=273, name=TEST-
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> TestUpgradeIndexMergePolicy], registration stack trace below.
> > at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> > at java.lang.Thread.getStackTrace(Thread.java:1552)
> > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(Rando
> mizedContext.java:173)
> > at
> org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java
> :740)
> > at
> org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:
> 1376)
> > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 273)
> > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 265)
> > at
> org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNee
> ded(BaseMergePolicyTestCase.java:37)
> > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> > at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.j
> ava:62)
> > at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces
> sorImpl.java:43)
> > at java.lang.reflect.Method.invoke(Method.java:498)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomize
> dRunner.java:1764)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Rando
> mizedRunner.java:871)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Rando
> mizedRunner.java:907)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rand
> omizedRunner.java:921)
> > at
> org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRule
> SetupTeardownChained.java:49)
> > at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA
> fterRule.java:45)
> > at
> org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleTh
> readAndTestName.java:48)
> > at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule
> IgnoreAfterMaxFailures.java:64)
> > at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure
> .java:47)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.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(ThreadL
> eakControl.java:460)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran
> domizedRunner.java:880)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Rando
> mizedRunner.java:781)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Rando
> mizedRunner.java:816)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Rando
> mizedRunner.java:827)
> > at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA
> fterRule.java:45)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreCl
> assName.java:41)
> > at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet
> hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> > at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet
> hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAss
> ertionsRequired.java:53)
> > at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure
> .java:47)
> > at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule
> IgnoreAfterMaxFailures.java:64)
> > at
> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnore
> TestSuites.java:54)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.
> run(ThreadLeakControl.java:367)
> > at java.lang.Thread.run(Thread.java:745)
> > Caused by: java.lang.AssertionError: Directory not closed:
> MockDirectoryWrapper(RAMDirectory@6e7a8241
> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe <ma...@264577fe> )
> > at org.junit.Assert.fail(Assert.java:93)
> > at
> org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(Rando
> mizedRunner.java:738)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(Rando
> mizedRunner.java:735)
> > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(R
> andomizedContext.java:221)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4.afterAlways(Ran
> domizedRunner.java:751)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:43)
> > ... 2 more
> >
> >
> >
> >
> > Build Log:
> > [...truncated 654 lines...]
> > [junit4] Suite: org.apache.lucene.index.TestUpgradeIndexMergePolicy
> > [junit4] IGNOR/A 0.01s J1 |
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded
> > [junit4] > Assumption #1: this test cannot tolerate random forceMerges
> > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {},
> docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {},
> locale=it-CH, timezone=Africa/Accra
> > [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation
> 1.8.0_72 (64-bit)/cpus=12,threads=1,free=451020912,total=529530880
> > [junit4] 2> NOTE: All tests run in this JVM:
> [TestDocumentsWriterStallControl, TestLockFactory, TestFilterDirectory,
> TestBlockPostingsFormat3, TestFlex, TestUnicodeUtil, TestDocument,
> TestTermScorer, TestIndexWriterExceptions, TestSpansAdvanced2,
> TestLucene50StoredFieldsFormatHighCompression, TestCrash,
> TestSpanMultiTermQueryWrapper, TestFixedBitSet, TestSegmentMerger,
> TestAllFilesHaveCodecHeader, TestTermRangeQuery,
> TestWeakIdentityMap, TestAttributeSource, TestBinaryTerms,
> TestBM25Similarity, TestByteBlockPool,
> TestIndexWriterThreadsToSegments, TestSmallFloat,
> Test2BSortedDocValuesOrds, TestFlushByRamOrCountsPolicy,
> TestBytesRefAttImpl, TestIndexWriterDeleteByQuery,
> TestTransactionRollback, TestDateTools, TestAutomaton, TestPrefixFilter,
> TestMultiCollector, TestDocValuesScoring, TestBooleanMinShouldMatch,
> TestSPIClassIterator, TestOmitPositions, TestDuelingCodecsAtNight,
> Test4GBStoredFields, TestMutableValues, TestBasics, TestMathUtil,
> TestUpgradeIndexMergePolicy]
> > [junit4] 2> NOTE: reproduce with: ant test -
> Dtestcase=TestUpgradeIndexMergePolicy -Dtests.seed=8C40C18106C3F5CD
> -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -
> Dtests.timezone=Africa/Accra -Dtests.asserts=true -
> Dtests.file.encoding=US-ASCII
> > [junit4] ERROR 0.00s J1 | TestUpgradeIndexMergePolicy (suite) <<<
> > [junit4] > Throwable #1:
> com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in
> scope SUITE failed to close. Resource was registered from thread
> Thread[id=273, name=TEST-
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> TestUpgradeIndexMergePolicy], registration stack trace below.
> > [junit4] > at
> __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> > [junit4] > at java.lang.Thread.getStackTrace(Thread.java:1552)
> > [junit4] > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(Rando
> mizedContext.java:173)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java
> :740)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:
> 1376)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 273)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 265)
> > [junit4] > at
> org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNee
> ded(BaseMergePolicyTestCase.java:37)
> > [junit4] > at java.lang.Thread.run(Thread.java:745)
> > [junit4] > Caused by: java.lang.AssertionError: Directory not closed:
> MockDirectoryWrapper(RAMDirectory@6e7a8241
> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe <ma...@264577fe> )
> > [junit4] > at
> org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> > [junit4] > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(R
> andomizedContext.java:221)
> > [junit4] > ... 2 more
> > [junit4] Completed [139/421 (1!)] on J1 in 0.04s, 1 test, 1 error, 1 skipped
> <<< FAILURES!
> >
> > [...truncated 906 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/lucene/build.xml:50: 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: 421 suites (1 ignored), 3386 tests, 1
> suite-level error, 49 ignored (45 assumptions) [seed: 8C40C18106C3F5CD]
> >
> > Total time: 5 minutes 58 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: dev-unsubscribe@lucene.apache.org <javascript:;>
> > For additional commands, e-mail: dev-help@lucene.apache.org <javascript:;>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org <javascript:;>
> For additional commands, e-mail: dev-help@lucene.apache.org <javascript:;>
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org <javascript:;>
For additional commands, e-mail: dev-help@lucene.apache.org <javascript:;>
--
Mike McCandless
http://blog.mikemccandless.com
Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16
- Still Failing!
Posted by Michael McCandless <lu...@mikemccandless.com>.
OK I will wait...
On Tuesday, February 16, 2016, Uwe Schindler <uw...@thetaphi.de> wrote:
> Hi Mike,
>
> after discussing with Robert, we improved the 5.x chacks, so we fail
> early, also on non-compatible JVMs like J9, JRockit, Zing, whatever.
> The new patch does not disable Java 9 at all, it just checks more pedantic.
>
> I want a review of Robert on it:
> https://issues.apache.org/jira/browse/LUCENE-6989
>
> Can you wait with respin?
>
> Uwe
>
> -----
> Uwe Schindler
> H.-H.-Meier-Allee 63, D-28213 Bremen
> http://www.thetaphi.de
> eMail: uwe@thetaphi.de <javascript:;>
>
>
> > -----Original Message-----
> > From: Michael McCandless [mailto:lucene@mikemccandless.com
> <javascript:;>]
> > Sent: Tuesday, February 16, 2016 12:40 AM
> > To: Lucene/Solr dev <dev@lucene.apache.org <javascript:;>>
> > Subject: Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build
> # 16 -
> > Still Failing!
> >
> > I'll fix, test bug.
> >
> > Uwe, are you done pushing the mmap fixes to 5.5?
> >
> > Mike McCandless
> >
> > http://blog.mikemccandless.com
> >
> >
> > On Mon, Feb 15, 2016 at 6:37 PM, Policeman Jenkins Server
> > <jenkins@thetaphi.de <javascript:;>> wrote:
> > > Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/16/
> > > Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC
> > >
> > > 1 tests failed.
> > > FAILED:
> > junit.framework.TestSuite.org.apache.lucene.index.TestUpgradeIndexMerg
> > ePolicy
> > >
> > > Error Message:
> > > Resource in scope SUITE failed to close. Resource was registered from
> > thread Thread[id=273, name=TEST-
> > TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> > seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> > TestUpgradeIndexMergePolicy], registration stack trace below.
> > >
> > > Stack Trace:
> > > com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in
> > scope SUITE failed to close. Resource was registered from thread
> > Thread[id=273, name=TEST-
> > TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> > seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> > TestUpgradeIndexMergePolicy], registration stack trace below.
> > > at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> > > at java.lang.Thread.getStackTrace(Thread.java:1552)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(Rando
> > mizedContext.java:173)
> > > at
> > org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java
> > :740)
> > > at
> > org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:
> > 1376)
> > > at
> > org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> > 273)
> > > at
> > org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> > 265)
> > > at
> > org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNee
> > ded(BaseMergePolicyTestCase.java:37)
> > > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> > > at
> > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.j
> > ava:62)
> > > at
> > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces
> > sorImpl.java:43)
> > > at java.lang.reflect.Method.invoke(Method.java:498)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomize
> > dRunner.java:1764)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Rando
> > mizedRunner.java:871)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Rando
> > mizedRunner.java:907)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rand
> > omizedRunner.java:921)
> > > at
> > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRule
> > SetupTeardownChained.java:49)
> > > at
> > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA
> > fterRule.java:45)
> > > at
> > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleTh
> > readAndTestName.java:48)
> > > at
> > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule
> > IgnoreAfterMaxFailures.java:64)
> > > at
> > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure
> > .java:47)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.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(ThreadL
> > eakControl.java:460)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran
> > domizedRunner.java:880)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Rando
> > mizedRunner.java:781)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Rando
> > mizedRunner.java:816)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Rando
> > mizedRunner.java:827)
> > > at
> > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA
> > fterRule.java:45)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.java:36)
> > > at
> > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreCl
> > assName.java:41)
> > > at
> > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet
> > hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> > > at
> > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet
> > hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.java:36)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.java:36)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.java:36)
> > > at
> > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAss
> > ertionsRequired.java:53)
> > > at
> > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure
> > .java:47)
> > > at
> > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule
> > IgnoreAfterMaxFailures.java:64)
> > > at
> > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnore
> > TestSuites.java:54)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.java:36)
> > > at
> > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.
> > run(ThreadLeakControl.java:367)
> > > at java.lang.Thread.run(Thread.java:745)
> > > Caused by: java.lang.AssertionError: Directory not closed:
> > MockDirectoryWrapper(RAMDirectory@6e7a8241
> > lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
> > > at org.junit.Assert.fail(Assert.java:93)
> > > at
> >
> org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(Rando
> > mizedRunner.java:738)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(Rando
> > mizedRunner.java:735)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(R
> > andomizedContext.java:221)
> > > at
> > com.carrotsearch.randomizedtesting.RandomizedRunner$4.afterAlways(Ran
> > domizedRunner.java:751)
> > > at
> > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> > ementAdapter.java:43)
> > > ... 2 more
> > >
> > >
> > >
> > >
> > > Build Log:
> > > [...truncated 654 lines...]
> > > [junit4] Suite: org.apache.lucene.index.TestUpgradeIndexMergePolicy
> > > [junit4] IGNOR/A 0.01s J1 |
> > TestUpgradeIndexMergePolicy.testForceMergeNotNeeded
> > > [junit4] > Assumption #1: this test cannot tolerate random
> forceMerges
> > > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {},
> > docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {},
> > locale=it-CH, timezone=Africa/Accra
> > > [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation
> > 1.8.0_72 (64-bit)/cpus=12,threads=1,free=451020912,total=529530880
> > > [junit4] 2> NOTE: All tests run in this JVM:
> > [TestDocumentsWriterStallControl, TestLockFactory, TestFilterDirectory,
> > TestBlockPostingsFormat3, TestFlex, TestUnicodeUtil, TestDocument,
> > TestTermScorer, TestIndexWriterExceptions, TestSpansAdvanced2,
> > TestLucene50StoredFieldsFormatHighCompression, TestCrash,
> > TestSpanMultiTermQueryWrapper, TestFixedBitSet, TestSegmentMerger,
> > TestAllFilesHaveCodecHeader, TestTermRangeQuery,
> > TestWeakIdentityMap, TestAttributeSource, TestBinaryTerms,
> > TestBM25Similarity, TestByteBlockPool,
> > TestIndexWriterThreadsToSegments, TestSmallFloat,
> > Test2BSortedDocValuesOrds, TestFlushByRamOrCountsPolicy,
> > TestBytesRefAttImpl, TestIndexWriterDeleteByQuery,
> > TestTransactionRollback, TestDateTools, TestAutomaton, TestPrefixFilter,
> > TestMultiCollector, TestDocValuesScoring, TestBooleanMinShouldMatch,
> > TestSPIClassIterator, TestOmitPositions, TestDuelingCodecsAtNight,
> > Test4GBStoredFields, TestMutableValues, TestBasics, TestMathUtil,
> > TestUpgradeIndexMergePolicy]
> > > [junit4] 2> NOTE: reproduce with: ant test -
> > Dtestcase=TestUpgradeIndexMergePolicy -Dtests.seed=8C40C18106C3F5CD
> > -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -
> > Dtests.timezone=Africa/Accra -Dtests.asserts=true -
> > Dtests.file.encoding=US-ASCII
> > > [junit4] ERROR 0.00s J1 | TestUpgradeIndexMergePolicy (suite) <<<
> > > [junit4] > Throwable #1:
> > com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in
> > scope SUITE failed to close. Resource was registered from thread
> > Thread[id=273, name=TEST-
> > TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> > seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> > TestUpgradeIndexMergePolicy], registration stack trace below.
> > > [junit4] > at
> > __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> > > [junit4] > at
> java.lang.Thread.getStackTrace(Thread.java:1552)
> > > [junit4] > at
> > com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(Rando
> > mizedContext.java:173)
> > > [junit4] > at
> > org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java
> > :740)
> > > [junit4] > at
> > org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:
> > 1376)
> > > [junit4] > at
> > org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> > 273)
> > > [junit4] > at
> > org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> > 265)
> > > [junit4] > at
> > org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNee
> > ded(BaseMergePolicyTestCase.java:37)
> > > [junit4] > at java.lang.Thread.run(Thread.java:745)
> > > [junit4] > Caused by: java.lang.AssertionError: Directory not
> closed:
> > MockDirectoryWrapper(RAMDirectory@6e7a8241
> > lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
> > > [junit4] > at
> >
> org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> > > [junit4] > at
> > com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(R
> > andomizedContext.java:221)
> > > [junit4] > ... 2 more
> > > [junit4] Completed [139/421 (1!)] on J1 in 0.04s, 1 test, 1 error,
> 1 skipped
> > <<< FAILURES!
> > >
> > > [...truncated 906 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/lucene/build.xml:50: 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: 421 suites (1 ignored), 3386
> tests, 1
> > suite-level error, 49 ignored (45 assumptions) [seed: 8C40C18106C3F5CD]
> > >
> > > Total time: 5 minutes 58 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: dev-unsubscribe@lucene.apache.org
> <javascript:;>
> > > For additional commands, e-mail: dev-help@lucene.apache.org
> <javascript:;>
> >
> > ---------------------------------------------------------------------
> > To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org <javascript:;>
> > For additional commands, e-mail: dev-help@lucene.apache.org
> <javascript:;>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org <javascript:;>
> For additional commands, e-mail: dev-help@lucene.apache.org <javascript:;>
>
>
--
Mike McCandless
http://blog.mikemccandless.com
RE: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16 - Still Failing!
Posted by Uwe Schindler <uw...@thetaphi.de>.
Hi Mike,
after discussing with Robert, we improved the 5.x chacks, so we fail early, also on non-compatible JVMs like J9, JRockit, Zing, whatever.
The new patch does not disable Java 9 at all, it just checks more pedantic.
I want a review of Robert on it: https://issues.apache.org/jira/browse/LUCENE-6989
Can you wait with respin?
Uwe
-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
http://www.thetaphi.de
eMail: uwe@thetaphi.de
> -----Original Message-----
> From: Michael McCandless [mailto:lucene@mikemccandless.com]
> Sent: Tuesday, February 16, 2016 12:40 AM
> To: Lucene/Solr dev <de...@lucene.apache.org>
> Subject: Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16 -
> Still Failing!
>
> I'll fix, test bug.
>
> Uwe, are you done pushing the mmap fixes to 5.5?
>
> Mike McCandless
>
> http://blog.mikemccandless.com
>
>
> On Mon, Feb 15, 2016 at 6:37 PM, Policeman Jenkins Server
> <je...@thetaphi.de> wrote:
> > Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/16/
> > Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC
> >
> > 1 tests failed.
> > FAILED:
> junit.framework.TestSuite.org.apache.lucene.index.TestUpgradeIndexMerg
> ePolicy
> >
> > Error Message:
> > Resource in scope SUITE failed to close. Resource was registered from
> thread Thread[id=273, name=TEST-
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> TestUpgradeIndexMergePolicy], registration stack trace below.
> >
> > Stack Trace:
> > com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in
> scope SUITE failed to close. Resource was registered from thread
> Thread[id=273, name=TEST-
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> TestUpgradeIndexMergePolicy], registration stack trace below.
> > at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> > at java.lang.Thread.getStackTrace(Thread.java:1552)
> > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(Rando
> mizedContext.java:173)
> > at
> org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java
> :740)
> > at
> org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:
> 1376)
> > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 273)
> > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 265)
> > at
> org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNee
> ded(BaseMergePolicyTestCase.java:37)
> > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> > at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.j
> ava:62)
> > at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces
> sorImpl.java:43)
> > at java.lang.reflect.Method.invoke(Method.java:498)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomize
> dRunner.java:1764)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Rando
> mizedRunner.java:871)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Rando
> mizedRunner.java:907)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rand
> omizedRunner.java:921)
> > at
> org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRule
> SetupTeardownChained.java:49)
> > at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA
> fterRule.java:45)
> > at
> org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleTh
> readAndTestName.java:48)
> > at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule
> IgnoreAfterMaxFailures.java:64)
> > at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure
> .java:47)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.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(ThreadL
> eakControl.java:460)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran
> domizedRunner.java:880)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Rando
> mizedRunner.java:781)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Rando
> mizedRunner.java:816)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Rando
> mizedRunner.java:827)
> > at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA
> fterRule.java:45)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreCl
> assName.java:41)
> > at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet
> hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> > at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet
> hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAss
> ertionsRequired.java:53)
> > at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure
> .java:47)
> > at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule
> IgnoreAfterMaxFailures.java:64)
> > at
> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnore
> TestSuites.java:54)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:36)
> > at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.
> run(ThreadLeakControl.java:367)
> > at java.lang.Thread.run(Thread.java:745)
> > Caused by: java.lang.AssertionError: Directory not closed:
> MockDirectoryWrapper(RAMDirectory@6e7a8241
> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
> > at org.junit.Assert.fail(Assert.java:93)
> > at
> org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(Rando
> mizedRunner.java:738)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(Rando
> mizedRunner.java:735)
> > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(R
> andomizedContext.java:221)
> > at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4.afterAlways(Ran
> domizedRunner.java:751)
> > at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat
> ementAdapter.java:43)
> > ... 2 more
> >
> >
> >
> >
> > Build Log:
> > [...truncated 654 lines...]
> > [junit4] Suite: org.apache.lucene.index.TestUpgradeIndexMergePolicy
> > [junit4] IGNOR/A 0.01s J1 |
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded
> > [junit4] > Assumption #1: this test cannot tolerate random forceMerges
> > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {},
> docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {},
> locale=it-CH, timezone=Africa/Accra
> > [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation
> 1.8.0_72 (64-bit)/cpus=12,threads=1,free=451020912,total=529530880
> > [junit4] 2> NOTE: All tests run in this JVM:
> [TestDocumentsWriterStallControl, TestLockFactory, TestFilterDirectory,
> TestBlockPostingsFormat3, TestFlex, TestUnicodeUtil, TestDocument,
> TestTermScorer, TestIndexWriterExceptions, TestSpansAdvanced2,
> TestLucene50StoredFieldsFormatHighCompression, TestCrash,
> TestSpanMultiTermQueryWrapper, TestFixedBitSet, TestSegmentMerger,
> TestAllFilesHaveCodecHeader, TestTermRangeQuery,
> TestWeakIdentityMap, TestAttributeSource, TestBinaryTerms,
> TestBM25Similarity, TestByteBlockPool,
> TestIndexWriterThreadsToSegments, TestSmallFloat,
> Test2BSortedDocValuesOrds, TestFlushByRamOrCountsPolicy,
> TestBytesRefAttImpl, TestIndexWriterDeleteByQuery,
> TestTransactionRollback, TestDateTools, TestAutomaton, TestPrefixFilter,
> TestMultiCollector, TestDocValuesScoring, TestBooleanMinShouldMatch,
> TestSPIClassIterator, TestOmitPositions, TestDuelingCodecsAtNight,
> Test4GBStoredFields, TestMutableValues, TestBasics, TestMathUtil,
> TestUpgradeIndexMergePolicy]
> > [junit4] 2> NOTE: reproduce with: ant test -
> Dtestcase=TestUpgradeIndexMergePolicy -Dtests.seed=8C40C18106C3F5CD
> -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -
> Dtests.timezone=Africa/Accra -Dtests.asserts=true -
> Dtests.file.encoding=US-ASCII
> > [junit4] ERROR 0.00s J1 | TestUpgradeIndexMergePolicy (suite) <<<
> > [junit4] > Throwable #1:
> com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in
> scope SUITE failed to close. Resource was registered from thread
> Thread[id=273, name=TEST-
> TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-
> seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-
> TestUpgradeIndexMergePolicy], registration stack trace below.
> > [junit4] > at
> __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> > [junit4] > at java.lang.Thread.getStackTrace(Thread.java:1552)
> > [junit4] > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(Rando
> mizedContext.java:173)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java
> :740)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:
> 1376)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 273)
> > [junit4] > at
> org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1
> 265)
> > [junit4] > at
> org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNee
> ded(BaseMergePolicyTestCase.java:37)
> > [junit4] > at java.lang.Thread.run(Thread.java:745)
> > [junit4] > Caused by: java.lang.AssertionError: Directory not closed:
> MockDirectoryWrapper(RAMDirectory@6e7a8241
> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
> > [junit4] > at
> org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> > [junit4] > at
> com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(R
> andomizedContext.java:221)
> > [junit4] > ... 2 more
> > [junit4] Completed [139/421 (1!)] on J1 in 0.04s, 1 test, 1 error, 1 skipped
> <<< FAILURES!
> >
> > [...truncated 906 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/lucene/build.xml:50: 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: 421 suites (1 ignored), 3386 tests, 1
> suite-level error, 49 ignored (45 assumptions) [seed: 8C40C18106C3F5CD]
> >
> > Total time: 5 minutes 58 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: dev-unsubscribe@lucene.apache.org
> > For additional commands, e-mail: dev-help@lucene.apache.org
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16
- Still Failing!
Posted by Michael McCandless <lu...@mikemccandless.com>.
I'll fix, test bug.
Uwe, are you done pushing the mmap fixes to 5.5?
Mike McCandless
http://blog.mikemccandless.com
On Mon, Feb 15, 2016 at 6:37 PM, Policeman Jenkins Server
<je...@thetaphi.de> wrote:
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/16/
> Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC
>
> 1 tests failed.
> FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestUpgradeIndexMergePolicy
>
> Error Message:
> Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=273, name=TEST-TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-TestUpgradeIndexMergePolicy], registration stack trace below.
>
> Stack Trace:
> com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=273, name=TEST-TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-TestUpgradeIndexMergePolicy], registration stack trace below.
> at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> at java.lang.Thread.getStackTrace(Thread.java:1552)
> at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:173)
> at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:740)
> at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:1376)
> at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1273)
> at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1265)
> at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:37)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:498)
> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 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 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)
> Caused by: java.lang.AssertionError: Directory not closed: MockDirectoryWrapper(RAMDirectory@6e7a8241 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
> at org.junit.Assert.fail(Assert.java:93)
> at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(RandomizedRunner.java:738)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(RandomizedRunner.java:735)
> at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:221)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.afterAlways(RandomizedRunner.java:751)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
> ... 2 more
>
>
>
>
> Build Log:
> [...truncated 654 lines...]
> [junit4] Suite: org.apache.lucene.index.TestUpgradeIndexMergePolicy
> [junit4] IGNOR/A 0.01s J1 | TestUpgradeIndexMergePolicy.testForceMergeNotNeeded
> [junit4] > Assumption #1: this test cannot tolerate random forceMerges
> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=it-CH, timezone=Africa/Accra
> [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=12,threads=1,free=451020912,total=529530880
> [junit4] 2> NOTE: All tests run in this JVM: [TestDocumentsWriterStallControl, TestLockFactory, TestFilterDirectory, TestBlockPostingsFormat3, TestFlex, TestUnicodeUtil, TestDocument, TestTermScorer, TestIndexWriterExceptions, TestSpansAdvanced2, TestLucene50StoredFieldsFormatHighCompression, TestCrash, TestSpanMultiTermQueryWrapper, TestFixedBitSet, TestSegmentMerger, TestAllFilesHaveCodecHeader, TestTermRangeQuery, TestWeakIdentityMap, TestAttributeSource, TestBinaryTerms, TestBM25Similarity, TestByteBlockPool, TestIndexWriterThreadsToSegments, TestSmallFloat, Test2BSortedDocValuesOrds, TestFlushByRamOrCountsPolicy, TestBytesRefAttImpl, TestIndexWriterDeleteByQuery, TestTransactionRollback, TestDateTools, TestAutomaton, TestPrefixFilter, TestMultiCollector, TestDocValuesScoring, TestBooleanMinShouldMatch, TestSPIClassIterator, TestOmitPositions, TestDuelingCodecsAtNight, Test4GBStoredFields, TestMutableValues, TestBasics, TestMathUtil, TestUpgradeIndexMergePolicy]
> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestUpgradeIndexMergePolicy -Dtests.seed=8C40C18106C3F5CD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -Dtests.timezone=Africa/Accra -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
> [junit4] ERROR 0.00s J1 | TestUpgradeIndexMergePolicy (suite) <<<
> [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=273, name=TEST-TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-TestUpgradeIndexMergePolicy], registration stack trace below.
> [junit4] > at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
> [junit4] > at java.lang.Thread.getStackTrace(Thread.java:1552)
> [junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:173)
> [junit4] > at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:740)
> [junit4] > at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:1376)
> [junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1273)
> [junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1265)
> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:37)
> [junit4] > at java.lang.Thread.run(Thread.java:745)
> [junit4] > Caused by: java.lang.AssertionError: Directory not closed: MockDirectoryWrapper(RAMDirectory@6e7a8241 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
> [junit4] > at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
> [junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:221)
> [junit4] > ... 2 more
> [junit4] Completed [139/421 (1!)] on J1 in 0.04s, 1 test, 1 error, 1 skipped <<< FAILURES!
>
> [...truncated 906 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/lucene/build.xml:50: 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: 421 suites (1 ignored), 3386 tests, 1 suite-level error, 49 ignored (45 assumptions) [seed: 8C40C18106C3F5CD]
>
> Total time: 5 minutes 58 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: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 16 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/16/
Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestUpgradeIndexMergePolicy
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=273, name=TEST-TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-TestUpgradeIndexMergePolicy], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=273, name=TEST-TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-TestUpgradeIndexMergePolicy], registration stack trace below.
at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
at java.lang.Thread.getStackTrace(Thread.java:1552)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:173)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:740)
at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:1376)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1273)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1265)
at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:37)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 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 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)
Caused by: java.lang.AssertionError: Directory not closed: MockDirectoryWrapper(RAMDirectory@6e7a8241 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4$1.apply(RandomizedRunner.java:735)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:221)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.afterAlways(RandomizedRunner.java:751)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
... 2 more
Build Log:
[...truncated 654 lines...]
[junit4] Suite: org.apache.lucene.index.TestUpgradeIndexMergePolicy
[junit4] IGNOR/A 0.01s J1 | TestUpgradeIndexMergePolicy.testForceMergeNotNeeded
[junit4] > Assumption #1: this test cannot tolerate random forceMerges
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=it-CH, timezone=Africa/Accra
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=12,threads=1,free=451020912,total=529530880
[junit4] 2> NOTE: All tests run in this JVM: [TestDocumentsWriterStallControl, TestLockFactory, TestFilterDirectory, TestBlockPostingsFormat3, TestFlex, TestUnicodeUtil, TestDocument, TestTermScorer, TestIndexWriterExceptions, TestSpansAdvanced2, TestLucene50StoredFieldsFormatHighCompression, TestCrash, TestSpanMultiTermQueryWrapper, TestFixedBitSet, TestSegmentMerger, TestAllFilesHaveCodecHeader, TestTermRangeQuery, TestWeakIdentityMap, TestAttributeSource, TestBinaryTerms, TestBM25Similarity, TestByteBlockPool, TestIndexWriterThreadsToSegments, TestSmallFloat, Test2BSortedDocValuesOrds, TestFlushByRamOrCountsPolicy, TestBytesRefAttImpl, TestIndexWriterDeleteByQuery, TestTransactionRollback, TestDateTools, TestAutomaton, TestPrefixFilter, TestMultiCollector, TestDocValuesScoring, TestBooleanMinShouldMatch, TestSPIClassIterator, TestOmitPositions, TestDuelingCodecsAtNight, Test4GBStoredFields, TestMutableValues, TestBasics, TestMathUtil, TestUpgradeIndexMergePolicy]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestUpgradeIndexMergePolicy -Dtests.seed=8C40C18106C3F5CD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -Dtests.timezone=Africa/Accra -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestUpgradeIndexMergePolicy (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=273, name=TEST-TestUpgradeIndexMergePolicy.testForceMergeNotNeeded-seed#[8C40C18106C3F5CD], state=RUNNABLE, group=TGRP-TestUpgradeIndexMergePolicy], registration stack trace below.
[junit4] > at __randomizedtesting.SeedInfo.seed([8C40C18106C3F5CD]:0)
[junit4] > at java.lang.Thread.getStackTrace(Thread.java:1552)
[junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:173)
[junit4] > at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:740)
[junit4] > at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:1376)
[junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1273)
[junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:1265)
[junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:37)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.AssertionError: Directory not closed: MockDirectoryWrapper(RAMDirectory@6e7a8241 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@264577fe)
[junit4] > at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:46)
[junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:221)
[junit4] > ... 2 more
[junit4] Completed [139/421 (1!)] on J1 in 0.04s, 1 test, 1 error, 1 skipped <<< FAILURES!
[...truncated 906 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/lucene/build.xml:50: 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: 421 suites (1 ignored), 3386 tests, 1 suite-level error, 49 ignored (45 assumptions) [seed: 8C40C18106C3F5CD]
Total time: 5 minutes 58 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