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 2015/05/25 22:11:40 UTC
[JENKINS] Lucene-Solr-5.x-Windows (64bit/jdk1.7.0_80) - Build #
4729 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4729/
Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.update.HardAutoCommitTest.testCommitWithin
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([FC41AA66F118B9C1:4693C51E723657D4]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:795)
at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
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:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:q=id:529&version=2.2&qt=standard&start=0&rows=20
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:788)
... 40 more
Build Log:
[...truncated 9853 lines...]
[junit4] Suite: org.apache.solr.update.HardAutoCommitTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001
[junit4] 2> 440073 T2149 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 440073 T2149 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 440073 T2149 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 440075 T2149 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 440076 T2149 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 440144 T2149 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 440163 T2149 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.3.0
[junit4] 2> 440199 T2149 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 440199 T2149 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 440206 T2149 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 440471 T2149 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 440480 T2149 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 440481 T2149 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 440487 T2149 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 440489 T2149 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 440491 T2149 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 440491 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 440492 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 440492 T2149 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 440493 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 440493 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 440493 T2149 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 440494 T2149 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr
[junit4] 2> 440494 T2149 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\'
[junit4] 2> 440508 T2149 oasc.CoreContainer.<init> New CoreContainer 1842458019
[junit4] 2> 440508 T2149 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\]
[junit4] 2> 440509 T2149 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib
[junit4] 2> 440509 T2149 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib).
[junit4] 2> 440519 T2149 oashc.HttpShardHandlerFactory.init created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 440522 T2149 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 440523 T2149 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 440523 T2149 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 440524 T2149 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 440524 T2149 oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
[junit4] 2> 440526 T2149 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {config=solrconfig.xml, schema=schema.xml, instanceDir=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\, loadOnStartup=true, shard=shard1, collection=collection1, transient=false, name=collection1, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001}
[junit4] 2> 440526 T2150 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 440528 T2150 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 440528 T2150 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 440549 T2150 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 440565 T2150 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.3.0
[junit4] 2> 440600 T2150 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 440600 T2150 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 440607 T2150 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 440889 T2150 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 440898 T2150 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 440900 T2150 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 440909 T2150 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 440912 T2150 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 440917 T2150 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 440918 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 440919 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 440919 T2150 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 440919 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 440919 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 440919 T2150 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 440919 T2150 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 440920 T2150 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
[junit4] 2> 440920 T2150 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b839a22
[junit4] 2> 440921 T2150 x:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\
[junit4] 2> 440921 T2150 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index/
[junit4] 2> 440922 T2150 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 440922 T2150 x:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index
[junit4] 2> 440923 T2150 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=32.1904296875, floorSegmentMB=1.330078125, forceMergeDeletesPctAllowed=11.224153620713647, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 440923 T2150 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43f617fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51a105e6),segFN=segments_1,generation=1}
[junit4] 2> 440923 T2150 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 440930 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 440930 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 440930 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 440931 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 440931 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 440931 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 440932 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 440932 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 440932 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 440933 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 440933 T2150 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 440933 T2150 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 440940 T2150 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 440943 T2150 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 440944 T2150 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 440947 T2150 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 440951 T2150 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 440954 T2150 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /mlt,/replication,defaults,/admin/ping,/admin/threads,/config,dismax,/admin/file,/search-facet-invariants,/admin/segments,spellCheckWithWordbreak,spellCheckCompRH_Direct,/update/json,/get,/admin/plugins,spellCheckCompRH,mltrh,/update/json/docs,spellCheckCompRH1,/admin/luke,mock,/debug/dump,/terms,/schema,/search-facet-def,spellCheckWithWordbreak_Direct,/admin/mbeans,/admin/logging,/update/csv,standard,tvrh,/admin/system,/update,/admin/properties,lazy
[junit4] 2> 440955 T2150 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 440956 T2150 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 440957 T2150 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 440957 T2150 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 440958 T2150 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 440958 T2150 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 440958 T2150 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.788816591092725]
[junit4] 2> 440959 T2150 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43f617fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51a105e6),segFN=segments_1,generation=1}
[junit4] 2> 440959 T2150 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 440959 T2150 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@3477f175[collection1] main
[junit4] 2> 440959 T2150 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 440960 T2150 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 440960 T2150 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 440960 T2150 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 440968 T2150 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 440995 T2150 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 440995 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 440996 T2150 x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field
[junit4] 2> 440996 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 440996 T2150 x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 440996 T2150 x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 440997 T2150 x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in index or recent updates, using new clock 1502171106156478464
[junit4] 2> 440996 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 440997 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 440997 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 440997 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 440997 T2150 x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 1 ms to seed version buckets with highest version 1502171106156478464
[junit4] 2> 440997 T2150 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 440997 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 440998 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 440999 T2151 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 441000 T2149 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 441000 T2151 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3477f175[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 441002 T2149 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin
[junit4] 2> ASYNC NEW_CORE C279 name=collection1 org.apache.solr.core.SolrCore@4d5539af
[junit4] 2> 441004 T2149 C279 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1502171106163818496)} 0 1
[junit4] 2> 441006 T2149 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 441007 T2149 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 441007 T2149 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 441046 T2149 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 441063 T2149 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.3.0
[junit4] 2> 441114 T2149 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 441115 T2149 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 441121 T2149 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 441540 T2149 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 441626 T2149 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 441629 T2149 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 441636 T2149 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 441657 T2149 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 441660 T2149 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 441660 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 441661 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 441661 T2149 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 441662 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 441662 T2149 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 441662 T2149 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 441681 T2149 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\]
[junit4] 2> 441681 T2149 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b839a22
[junit4] 2> 441682 T2149 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index/
[junit4] 2> 441687 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 441688 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 441688 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 441688 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 441688 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 441688 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 441689 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 441689 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 441689 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 441689 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 441689 T2149 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 441689 T2149 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 441695 T2149 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 441696 T2149 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 441699 T2149 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 441701 T2149 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 441717 T2149 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 441720 T2149 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /mlt,/replication,defaults,/admin/ping,/admin/threads,/config,dismax,/admin/file,/search-facet-invariants,/admin/segments,spellCheckWithWordbreak,spellCheckCompRH_Direct,/update/json,/get,/admin/plugins,spellCheckCompRH,mltrh,/update/json/docs,spellCheckCompRH1,/admin/luke,mock,/debug/dump,/terms,/schema,/search-facet-def,spellCheckWithWordbreak_Direct,/admin/mbeans,/admin/logging,/update/csv,standard,tvrh,/admin/system,/update,/admin/properties,lazy
[junit4] 2> 441721 T2149 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 441722 T2149 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 441735 T2149 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 441735 T2149 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 441776 T2149 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@34bbfa63[collection1] main
[junit4] 2> 441787 T2149 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 441787 T2149 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 441787 T2149 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 441792 T2149 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 441794 T2149 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 441847 T2149 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 441849 T2149 x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field
[junit4] 2> 441849 T2149 x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 441850 T2149 x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 441851 T2149 x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 2 ms to seed version buckets with highest version 1502171106163818496
[junit4] 2> 441851 T2149 x:collection1 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 441851 T2149 x:collection1 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 441851 T2149 x:collection1 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 441852 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 441852 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 441852 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 441852 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 441853 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 441853 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 441854 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 441854 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 441854 T2153 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 441855 T2153 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34bbfa63[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 441856 T2149 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=1.458984375, floorSegmentMB=0.5380859375, forceMergeDeletesPctAllowed=4.686031518508513, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17914822261973226
[junit4] 2> 441856 T2149 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43f617fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51a105e6),segFN=segments_1,generation=1}
[junit4] 2> 441859 T2149 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 441860 T2149 x:collection1 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 441860 T2149 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@6684c1e1[collection1] main
[junit4] 2> 441860 T2149 x:collection1 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 441860 T2149 x:collection1 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4d5539af
[junit4] 2> 441861 T2149 x:collection1 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=1,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 441861 T2149 x:collection1 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 441872 T2149 x:collection1 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1502171107073982464)]} 0 4
[junit4] 2> ASYNC NEW_CORE C280 name=collection1 org.apache.solr.core.SolrCore@2633798c
[junit4] 2> 441873 T2149 x:collection1 C280 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&version=2.2&qt=standard&start=0&rows=20} hits=0 status=0 QTime=1
[junit4] 2> 441890 T2153 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6684c1e1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 442074 T2149 x:collection1 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1502171107285794816)]} 0 206
[junit4] 2> 442075 T2149 x:collection1 C280 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:530&version=2.2&qt=standard&start=0&rows=20} hits=0 status=0 QTime=1
[junit4] 2> 442076 T2149 x:collection1 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1502171107288940544)]} 0 208
[junit4] 2> 442077 T2149 x:collection1 C280 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&version=2.2&qt=standard&start=0&rows=20} hits=0 status=0 QTime=1
[junit4] 2> 442078 T2149 x:collection1 oas.SolrTestCaseJ4.assertQ ERROR REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&version=2.2&qt=standard&start=0&rows=20
[junit4] 2> 442079 T2149 x:collection1 oasc.SolrException.log ERROR REQUEST FAILED: q=id:529&version=2.2&qt=standard&start=0&rows=20:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&version=2.2&qt=standard&start=0&rows=20
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:788)
[junit4] 2> at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:606)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 442079 T2149 x:collection1 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HardAutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=FC41AA66F118B9C1 -Dtests.slow=true -Dtests.locale=ar_YE -Dtests.timezone=Europe/Tirane -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 1.08s J0 | HardAutoCommitTest.testCommitWithin <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([FC41AA66F118B9C1:4693C51E723657D4]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:795)
[junit4] > at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] > </response>
[junit4] > request was:q=id:529&version=2.2&qt=standard&start=0&rows=20
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:788)
[junit4] > ... 40 more
[junit4] 2> 442083 T2149 x:collection1 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 442083 T2149 x:collection1 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1842458019
[junit4] 2> 442084 T2149 x:collection1 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2633798c
[junit4] 2> 442084 T2149 x:collection1 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=1,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 442084 T2149 x:collection1 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 442084 T2149 x:collection1 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 442085 T2149 x:collection1 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 442085 T2149 x:collection1 C280 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
[junit4] 2> 442086 T2149 x:collection1 C280 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43f617fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51a105e6),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43f617fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51a105e6),segFN=segments_2,generation=2}
[junit4] 2> 442087 T2149 x:collection1 C280 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 442088 T2149 x:collection1 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 442097 T2149 x:collection1 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 442097 T2149 x:collection1 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index;done=false>>]
[junit4] 2> 442097 T2149 x:collection1 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\index
[junit4] 2> 442097 T2149 x:collection1 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\;done=false>>]
[junit4] 2> 442098 T2149 x:collection1 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001\init-core-data-001\
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.HardAutoCommitTest FC41AA66F118B9C1-001
[junit4] 2> NOTE: test params are: codec=Lucene50, sim=DefaultSimilarity, locale=ar_YE, timezone=Europe/Tirane
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=3,threads=1,free=71048872,total=476053504
[junit4] 2> NOTE: All tests run in this JVM: [TestLFUCache, RuleEngineTest, TestWordDelimiterFilterFactory, TestFastLRUCache, TestLRUCache, DistributedQueryComponentOptimizationTest, TestCloudInspectUtil, AnalyticsMergeStrategyTest, TestExpandComponent, CustomCollectionTest, TestFunctionQuery, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestStressVersions, HighlighterMaxOffsetTest, TestDefaultSearchFieldResource, QueryElevationComponentTest, DistribDocExpirationUpdateProcessorTest, DistributedDebugComponentTest, DocValuesTest, HdfsNNFailoverTest, TestLRUStatsCache, RulesTest, SynonymTokenizerTest, BadComponentTest, TestNonDefinedSimilarityFactory, DeleteShardTest, TestMiniSolrCloudClusterSSL, OutputWriterTest, TestMissingGroups, TestHashQParserPlugin, TestPivotHelperCode, TestStressRecovery, PreAnalyzedFieldTest, TestFieldResource, TestBM25SimilarityFactory, XmlUpdateRequestHandlerTest, MergeStrategyTest, AnalysisAfterCoreReloadTest, TestOrdValues, DirectSolrConnectionTest, SolrTestCaseJ4Test, SuggesterTest, UUIDFieldTest, TestPartialUpdateDeduplication, AssignTest, DocValuesMultiTest, TestSolrQueryParserResource, PeerSyncTest, TestQueryTypes, HdfsBasicDistributedZkTest, TestObjectReleaseTracker, HardAutoCommitTest]
[junit4] Completed [81/496] on J0 in 2.04s, 1 test, 1 error <<< FAILURES!
[...truncated 1329 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:536: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:484: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:229: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:511: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1433: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:991: There were test failures: 496 suites, 1973 tests, 1 error, 66 ignored (34 assumptions)
Total time: 75 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any