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 2014/03/10 14:23:05 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0-fcs) - Build #
1398 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1398/
Java: 64bit/jdk1.8.0-fcs -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT
Error Message:
SOLR-5815? : wrong maxDoc: core=org.apache.solr.core.SolrCore@5d1fbdfd searcher=Searcher@7b5d59d5[collection1] main{StandardDirectoryReader(segments_8:16 _4(5.0):C1 _5(5.0):C1)} expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: SOLR-5815? : wrong maxDoc: core=org.apache.solr.core.SolrCore@5d1fbdfd searcher=Searcher@7b5d59d5[collection1] main{StandardDirectoryReader(segments_8:16 _4(5.0):C1 _5(5.0):C1)} expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([16318555406A7EAC:A3B7E4D2FFABCC58]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:142)
at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:100)
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:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 10463 lines...]
[junit4] Suite: org.apache.solr.core.TestNonNRTOpen
[junit4] 2> 1639377 T4687 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1639380 T4687 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931
[junit4] 2> 1639381 T4687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1639381 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1639382 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1639382 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1639439 T4687 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1639446 T4687 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1639448 T4687 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 1639451 T4687 oass.IndexSchema.readSchema [null] Schema name=minimal
[junit4] 2> 1639455 T4687 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1639456 T4687 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1639457 T4687 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr
[junit4] 2> 1639457 T4687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/'
[junit4] 2> 1639490 T4687 oasc.CoreContainer.<init> New CoreContainer 72925335
[junit4] 2> 1639491 T4687 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/]
[junit4] 2> 1639491 T4687 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1639492 T4687 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1639492 T4687 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1639492 T4687 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1639492 T4687 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1639493 T4687 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1639493 T4687 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1639494 T4687 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1639494 T4687 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1639510 T4687 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1639511 T4687 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1639511 T4687 oasc.CoreContainer.load Host Name:
[junit4] 2> 1639516 T4688 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1639516 T4688 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1639517 T4688 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1639517 T4688 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1639517 T4688 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1639544 T4688 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1639550 T4688 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1639550 T4688 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 1639552 T4688 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 1639554 T4688 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1639555 T4688 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1639556 T4688 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/
[junit4] 2> 1639556 T4688 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1639557 T4688 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931
[junit4] 2> 1639557 T4688 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/index/
[junit4] 2> 1639557 T4688 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/index' doesn't exist. Creating new index...
[junit4] 2> 1639558 T4688 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index
[junit4] 2> 1639560 T4688 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_1,generation=1}
[junit4] 2> 1639560 T4688 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1639561 T4688 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1639561 T4688 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1639561 T4688 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1639567 T4688 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1639568 T4688 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1639568 T4688 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1639569 T4688 oass.SolrIndexSearcher.<init> Opening Searcher@745c84a1[collection1] main
[junit4] 2> 1639569 T4688 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1639569 T4689 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@745c84a1[collection1] main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1639571 T4687 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C2406 name=collection1 org.apache.solr.core.SolrCore@467c2d97
[junit4] 2> 1639574 T4687 C2406 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_1,generation=1}
[junit4] 2> 1639575 T4687 C2406 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1639577 T4687 C2406 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 5
[junit4] 2> 1639579 T4687 C2406 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1639599 T4687 C2406 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_2,generation=2}
[junit4] 2> 1639599 T4687 C2406 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1639613 T4687 C2406 oass.SolrIndexSearcher.<init> Opening Searcher@15d1b0ad[collection1] main
[junit4] 2> 1639613 T4687 C2406 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1639613 T4689 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15d1b0ad[collection1] main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4] 2> 1639614 T4687 C2406 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 35
[junit4] 2> 1639614 T4687 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1639614 T4687 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=72925335
[junit4] 2> 1639615 T4687 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@467c2d97
[junit4] 2> 1639615 T4687 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1639615 T4687 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1639615 T4687 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1639616 T4687 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1639616 T4687 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1639616 T4687 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1639617 T4687 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index;done=false>>]
[junit4] 2> 1639617 T4687 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index
[junit4] 2> 1639617 T4687 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931;done=false>>]
[junit4] 2> 1639618 T4687 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931
[junit4] 2> NOTE: per solr.test.leavedatadir, dataDir will not be removed: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931
[junit4] 2> 1639619 T4687 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1639619 T4687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1639620 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1639620 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1639621 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1639665 T4687 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1639682 T4687 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1639684 T4687 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 1639686 T4687 oass.IndexSchema.readSchema [null] Schema name=minimal
[junit4] 2> 1639690 T4687 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1639692 T4687 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1639692 T4687 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr
[junit4] 2> 1639692 T4687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/'
[junit4] 2> 1639729 T4687 oasc.CoreContainer.<init> New CoreContainer 870618142
[junit4] 2> 1639730 T4687 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/]
[junit4] 2> 1639731 T4687 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1639731 T4687 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1639732 T4687 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1639734 T4687 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1639734 T4687 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1639735 T4687 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1639735 T4687 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1639736 T4687 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1639736 T4687 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1639744 T4687 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1639744 T4687 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1639745 T4687 oasc.CoreContainer.load Host Name:
[junit4] 2> 1639747 T4691 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1639747 T4691 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1639748 T4691 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1639748 T4691 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1639748 T4691 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1639769 T4691 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1639773 T4691 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1639774 T4691 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 1639777 T4691 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 1639778 T4691 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1639779 T4691 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1639779 T4691 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/
[junit4] 2> 1639780 T4691 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1639780 T4691 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931
[junit4] 2> 1639781 T4691 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/index/
[junit4] 2> 1639782 T4691 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1639782 T4691 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1639782 T4691 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1639788 T4691 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1639789 T4691 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1639790 T4691 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1639790 T4691 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index
[junit4] 2> 1639792 T4691 oass.SolrIndexSearcher.<init> Opening Searcher@7e6db1c[collection1] main
[junit4] 2> 1639792 T4691 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1639793 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e6db1c[collection1] main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4] 2> 1639794 T4687 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 1639794 T4687 oasc.TestNonNRTOpen.assertNotNRT Checking notNRT & maxDoc=1 of core=org.apache.solr.core.SolrCore@744f05cc
[junit4] 2> 1639801 T4687 oas.SolrTestCaseJ4.setUp ###Starting testSharedCores
[junit4] 2> ASYNC NEW_CORE C2407 name=collection1 org.apache.solr.core.SolrCore@744f05cc
[junit4] 2> 1639801 T4687 C2407 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1639805 T4687 C2407 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_2,generation=2}
[junit4] 2> 1639805 T4687 C2407 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1639806 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 5
[junit4] 2> 1639809 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 2
[junit4] 2> 1639810 T4687 C2407 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1639834 T4687 C2407 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_2,generation=2}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_3,generation=3}
[junit4] 2> 1639834 T4687 C2407 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1639846 T4687 C2407 oass.SolrIndexSearcher.<init> Opening Searcher@7857910f[collection1] main
[junit4] 2> 1639847 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7857910f[collection1] main{StandardDirectoryReader(segments_3:6 _1(5.0):C1)}
[junit4] 2> 1639848 T4687 C2407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1639849 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 39
[junit4] 2> 1639849 T4687 C2407 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1639850 T4687 C2407 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1639852 T4687 C2407 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 1639852 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7857910f[collection1] main{StandardDirectoryReader(segments_3:6 _1(5.0):C1)}
[junit4] 2> 1639852 T4687 C2407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1639853 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {optimize=} 0 4
[junit4] 2> 1639854 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 1
[junit4] 2> 1639855 T4687 C2407 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1639873 T4687 C2407 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_3,generation=3}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_4,generation=4}
[junit4] 2> 1639875 T4687 C2407 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 1639890 T4687 C2407 oass.SolrIndexSearcher.<init> Opening Searcher@3db13286[collection1] main
[junit4] 2> 1639890 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3db13286[collection1] main{StandardDirectoryReader(segments_4:8 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 1639891 T4687 C2407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1639891 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 37
[junit4] 2> 1639893 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 1
[junit4] 2> 1639894 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 0
[junit4] 2> 1639894 T4687 C2407 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1639922 T4687 C2407 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_4,generation=4}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_5,generation=5}
[junit4] 2> 1639922 T4687 C2407 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 1639938 T4687 C2407 oass.SolrIndexSearcher.<init> Opening Searcher@59cb343b[collection1] main
[junit4] 2> 1639938 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59cb343b[collection1] main{StandardDirectoryReader(segments_5:10 _1(5.0):C1 _2(5.0):C1 _3(5.0):C2)}
[junit4] 2> 1639938 T4687 C2407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1639939 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 45
[junit4] 2> 1639940 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=foo2:doc} 0 1
[junit4] 2> 1639940 T4687 C2407 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1639959 T4687 C2407 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_5,generation=5}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_6,generation=6}
[junit4] 2> 1639959 T4687 C2407 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 1639978 T4687 C2407 oass.SolrIndexSearcher.<init> Opening Searcher@26a72655[collection1] main
[junit4] 2> 1639986 T4687 C2407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1639986 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26a72655[collection1] main{StandardDirectoryReader(segments_6:11 _1(5.0):C1 _2(5.0):C1 _3(5.0):C2/1:delGen=1)}
[junit4] 2> 1639987 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 47
[junit4] 2> 1639988 T4687 oas.SolrTestCaseJ4.tearDown ###Ending testSharedCores
[junit4] 2> 1639991 T4687 oas.SolrTestCaseJ4.setUp ###Starting testReaderIsNotNRT
[junit4] 2> 1639992 T4687 C2407 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1639992 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 1639995 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 2
[junit4] 2> 1639995 T4687 C2407 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1640026 T4687 C2407 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_6,generation=6}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_7,generation=7}
[junit4] 2> 1640026 T4687 C2407 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 1640055 T4687 C2407 oass.SolrIndexSearcher.<init> Opening Searcher@5b73776d[collection1] main
[junit4] 2> 1640055 T4692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5b73776d[collection1] main{StandardDirectoryReader(segments_7:14 _4(5.0):C1)}
[junit4] 2> 1640061 T4687 C2407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1640062 T4687 C2407 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 67
[junit4] 2> 1640063 T4687 oasc.TestNonNRTOpen.assertNotNRT Checking notNRT & maxDoc=1 of core=org.apache.solr.core.SolrCore@744f05cc
[junit4] 2> 1640063 T4687 oasc.TestNonNRTOpen.testReaderIsNotNRT Reloading core: org.apache.solr.core.SolrCore@744f05cc
[junit4] 2> 1640063 T4687 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1640063 T4687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1640065 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1640066 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1640066 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1640144 T4687 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1640151 T4687 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1640153 T4687 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 1640155 T4687 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 1640158 T4687 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1640160 T4687 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/
[junit4] 2> 1640163 T4687 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1640164 T4687 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/index/
[junit4] 2> 1640170 T4687 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1640170 T4687 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1640171 T4687 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1640176 T4687 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1640177 T4687 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1640178 T4687 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1640180 T4687 oass.SolrIndexSearcher.<init> Opening Searcher@6b080613[collection1] main
[junit4] 2> 1640181 T4694 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b080613[collection1] main{StandardDirectoryReader(segments_7:14 _4(5.0):C1)}
[junit4] 2> 1640181 T4687 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 1640181 T4687 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1640181 T4687 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 1640185 T4687 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_7,generation=7}
[junit4] 2> 1640185 T4687 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 1640186 T4687 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1640188 T4687 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 1640188 T4694 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b080613[collection1] main{StandardDirectoryReader(segments_7:14 _4(5.0):C1)}
[junit4] 2> 1640189 T4687 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 1640189 T4687 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@744f05cc
[junit4] 2> 1640189 T4687 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=3,cumulative_errors=0}
[junit4] 2> 1640190 T4687 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1640190 T4687 oasc.TestNonNRTOpen.assertNotNRT Checking notNRT & maxDoc=1 of core=org.apache.solr.core.SolrCore@5c0837d0
[junit4] 2> ASYNC NEW_CORE C2408 name=collection1 org.apache.solr.core.SolrCore@5c0837d0
[junit4] 2> 1640193 T4687 C2408 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 3
[junit4] 2> 1640196 T4687 C2408 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1640215 T4687 C2408 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_7,generation=7}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_8,generation=8}
[junit4] 2> 1640216 T4687 C2408 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 1640228 T4687 C2408 oass.SolrIndexSearcher.<init> Opening Searcher@77a349a[collection1] main
[junit4] 2> 1640228 T4694 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@77a349a[collection1] main{StandardDirectoryReader(segments_8:16 _4(5.0):C1 _5(5.0):C1)}
[junit4] 2> 1640229 T4687 C2408 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1640229 T4687 C2408 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 34
[junit4] 2> 1640230 T4687 oasc.TestNonNRTOpen.assertNotNRT Checking notNRT & maxDoc=2 of core=org.apache.solr.core.SolrCore@5c0837d0
[junit4] 2> 1640233 T4687 C2408 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 3
[junit4] 2> 1640233 T4687 oasc.TestNonNRTOpen.testReaderIsNotNRT Reloading core: org.apache.solr.core.SolrCore@5c0837d0
[junit4] 2> 1640234 T4687 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1640234 T4687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1640239 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1640241 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1640243 T4687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1640291 T4687 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1640298 T4687 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1640304 T4687 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 1640309 T4687 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 1640313 T4687 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1640315 T4687 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/
[junit4] 2> 1640315 T4687 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1640315 T4687 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestNonNRTOpen-1394454922931/index/
[junit4] 2> 1640319 T4687 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1640319 T4687 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1640319 T4687 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1640325 T4687 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1640326 T4687 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1640327 T4687 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1640332 T4687 oass.SolrIndexSearcher.<init> Opening Searcher@7b5d59d5[collection1] main
[junit4] 2> 1640333 T4687 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 1640333 T4687 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1640333 T4687 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 1640334 T4695 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b5d59d5[collection1] main{StandardDirectoryReader(segments_8:16 _4(5.0):C1 _5(5.0):C1)}
[junit4] 2> 1640348 T4687 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_8,generation=8}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_9,generation=9}
[junit4] 2> 1640348 T4687 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 1640362 T4687 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index,segFN=segments_9,generation=9}
[junit4] 2> 1640365 T4687 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 1640370 T4687 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1640380 T4687 oass.SolrIndexSearcher.<init> Opening Searcher@4fc15628[collection1] main
[junit4] 2> 1640380 T4687 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 1640380 T4687 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5c0837d0
[junit4] 2> 1640381 T4687 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1640382 T4687 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1640382 T4687 oasc.TestNonNRTOpen.assertNotNRT Checking notNRT & maxDoc=3 of core=org.apache.solr.core.SolrCore@5d1fbdfd
[junit4] 2> 1640383 T4687 oas.SolrTestCaseJ4.tearDown ###Ending testReaderIsNotNRT
[junit4] 2> 1640390 T4695 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4fc15628[collection1] main{StandardDirectoryReader(segments_9:18 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1)}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNonNRTOpen -Dtests.method=testReaderIsNotNRT -Dtests.seed=16318555406A7EAC -Dtests.slow=true -Dtests.locale=es_SV -Dtests.timezone=Asia/Ulaanbaatar -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.41s | TestNonNRTOpen.testReaderIsNotNRT <<<
[junit4] > Throwable #1: java.lang.AssertionError: SOLR-5815? : wrong maxDoc: core=org.apache.solr.core.SolrCore@5d1fbdfd searcher=Searcher@7b5d59d5[collection1] main{StandardDirectoryReader(segments_8:16 _4(5.0):C1 _5(5.0):C1)} expected:<3> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([16318555406A7EAC:A3B7E4D2FFABCC58]:0)
[junit4] > at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:142)
[junit4] > at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:100)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 1640402 T4687 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1640402 T4687 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=870618142
[junit4] 2> 1640402 T4687 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5d1fbdfd
[junit4] 2> 1640403 T4687 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1640403 T4687 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1640403 T4687 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1640403 T4687 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1640405 T4687 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1640405 T4687 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1640405 T4687 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index;done=false>>]
[junit4] 2> 1640405 T4687 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931/index
[junit4] 2> 1640406 T4687 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931;done=false>>]
[junit4] 2> 1640406 T4687 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/solrtest-TestNonNRTOpen-1394454922931
[junit4] 2> NOTE: test params are: codec=Lucene46: {bazz=PostingsFormat(name=Lucene41WithOrds), foo=PostingsFormat(name=MockSep), baz=PostingsFormat(name=Lucene41WithOrds), foo2=MockFixedIntBlock(blockSize=1619)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=es_SV, timezone=Asia/Ulaanbaatar
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0 (64-bit)/cpus=2,threads=1,free=70734568,total=284164096
[junit4] 2> NOTE: All tests run in this JVM: [MultiTermTest, TestRemoteStreaming, CacheHeaderTest, BasicDistributedZk2Test, TestDistribDocBasedVersion, TestMergePolicyConfig, DirectSolrSpellCheckerTest, TestSort, SpatialFilterTest, SolrIndexSplitterTest, MinimalSchemaTest, CursorMarkTest, QueryElevationComponentTest, TestFileDictionaryLookup, DocumentBuilderTest, BasicDistributedZkTest, SyncSliceTest, TestDistributedGrouping, DistributedQueryComponentCustomSortTest, DeleteInactiveReplicaTest, TestSolrXmlPersistence, TestCopyFieldCollectionResource, TestIndexingPerformance, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, TestCursorMarkWithoutUniqueKey, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, DistributedQueryComponentOptimizationTest, TestSolrQueryParserDefaultOperatorResource, TestQuerySenderNoQuery, TestCoreDiscovery, TestJmxMonitoredMap, TestValueSourceCache, LeaderElectionTest, CollectionsAPIDistributedZkTest, SuggesterFSTTest, TestOverriddenPrefixQueryForCustomFieldType, TestFaceting, TestStandardQParsers, TestCollapseQParserPlugin, LegacyHTMLStripCharFilterTest, IndexSchemaRuntimeFieldTest, IndexBasedSpellCheckerTest, TestXIncludeConfig, UniqFieldsUpdateProcessorFactoryTest, BJQParserTest, SoftAutoCommitTest, TestCustomSort, DateMathParserTest, MoreLikeThisHandlerTest, TestRandomMergePolicy, DOMUtilTest, CircularListTest, RegexBoostProcessorTest, TestQuerySenderListener, HdfsCollectionsAPIDistributedZkTest, TestNoOpRegenerator, EnumFieldTest, SolrInfoMBeanTest, FieldFacetExtrasTest, MigrateRouteKeyTest, TestDefaultSimilarityFactory, SpellPossibilityIteratorTest, TestManagedSchemaFieldResource, BadIndexSchemaTest, TestInitQParser, TestCSVResponseWriter, ExternalFileFieldSortTest, CoreAdminCreateDiscoverTest, OutputWriterTest, SampleTest, AssignTest, ConnectionManagerTest, TestNumberUtils, TestStressVersions, CursorPagingTest, TestDynamicFieldResource, PreAnalyzedFieldTest, SolrTestCaseJ4Test, TestBlendedInfixSuggestions, URLClassifyProcessorTest, PeerSyncTest, FastVectorHighlighterTest, ClusterStateUpdateTest, SpellingQueryConverterTest, DistributedQueryElevationComponentTest, AutoCommitTest, ChaosMonkeyNothingIsSafeTest, SortByFunctionTest, TestHighFrequencyDictionaryFactory, OpenCloseCoreStressTest, OverseerRolesTest, TestWriterPerf, TestSearcherReuse, BasicZkTest, TestDistributedMissingSort, JSONWriterTest, TestFieldCollectionResource, TestAtomicUpdateErrorCases, TestFieldTypeCollectionResource, TestUpdate, TestIndexSearcher, TestCollationFieldDocValues, TestAddFieldRealTimeGet, TestGroupingSearch, DirectUpdateHandlerOptimizeTest, TestLMJelinekMercerSimilarityFactory, PreAnalyzedUpdateProcessorTest, TestRTGBase, HdfsDirectoryTest, TriLevelCompositeIdRoutingTest, IndexSchemaTest, BlockDirectoryTest, OpenExchangeRatesOrgProviderTest, TestFastWriter, SimpleFacetsTest, TestNonNRTOpen]
[junit4] Completed in 1.07s, 2 tests, 1 failure <<< FAILURES!
[...truncated 809 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:490: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1274: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:907: There were test failures: 379 suites, 1622 tests, 1 failure, 54 ignored (19 assumptions)
Total time: 77 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-fcs -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure