Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4555/
Java: 32bit/jdk1.8.0_31 -server -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.update.AutoCommitTest.testMaxDocs

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([3761A067B2F5825D:8EE076B89E1F86D7]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:794)
        at 
org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:192)
        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: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:53)
        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:53)
        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">0</int></lst><result name="response" numFound="0" 
start="0"></result>
</response>

        request was:q=id:14&qt=standard&start=0&rows=20&version=2.2
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:787)
        ... 40 more




Build Log:
[...truncated 9749 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> Creating dataDir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001
   [junit4]   2> 1225695 T8717 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(true) and clientAuth (true)
   [junit4]   2> 1225696 T8717 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 1225697 T8717 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1225699 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1225700 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1225745 T8717 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1225753 T8717 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1225775 T8717 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1225776 T8717 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1225781 T8717 oass.IndexSchema.readSchema [null] Schema 
name=test
   [junit4]   2> 1225815 T3 oasc.CoreContainer.finalize ERROR CoreContainer was 
not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!  
instance=32059391
   [junit4]   2> 1225876 T8717 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1225883 T8717 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1225887 T8717 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1225893 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1225902 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1225905 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1225905 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1225905 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1225905 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1225906 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1225907 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1225907 T8717 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1225907 T8717 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr
   [junit4]   2> 1225908 T8717 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\'
   [junit4]   2> 1225924 T8717 oasc.CoreContainer.<init> New CoreContainer 
30050290
   [junit4]   2> 1225925 T8717 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\]
   [junit4]   2> 1225925 T8717 oasc.CoreContainer.load loading shared library: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib
   [junit4]   2> 1225925 T8717 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib).
   [junit4]   2> 1225933 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 600000
   [junit4]   2> 1225933 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: null
   [junit4]   2> 1225933 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 60000
   [junit4]   2> 1225934 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1225934 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1225934 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1225935 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1225935 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1225935 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1225935 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1225935 T8717 oashc.HttpShardHandlerFactory.getParameter 
Setting useRetries to: false
   [junit4]   2> 1225940 T8717 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1225941 T8717 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1225942 T8717 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1225942 T8717 oasc.CoreContainer.load Node Name: testNode
   [junit4]   1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, 
transient=false, schema=schema.xml, loadOnStartup=true, 
instanceDir=collection1, collection=collection1, 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001, shard=shard1}
   [junit4]   2> 1225982 T8718 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1225984 T8718 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1225984 T8718 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1226004 T8718 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1226015 T8718 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1226037 T8718 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1226037 T8718 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1226042 T8718 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1226135 T8718 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1226140 T8718 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1226143 T8718 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1226148 T8718 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1226150 T8718 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1226152 T8718 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1226153 T8718 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1226153 T8718 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1226153 T8718 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1226153 T8718 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1226154 T8718 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1226154 T8718 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1226154 T8718 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1226155 T8718 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\
   [junit4]   2> 1226155 T8718 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1a4872a
   [junit4]   2> 1226155 T8718 oasc.CachingDirectoryFactory.get return new 
directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\
   [junit4]   2> 1226156 T8718 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index/
   [junit4]   2> 1226156 T8718 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index' doesn't exist. Creating new 
index...
   [junit4]   2> 1226156 T8718 oasc.CachingDirectoryFactory.get return new 
directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index
   [junit4]   2> 1226156 T8718 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7053153818853181]
   [junit4]   2> 1226157 T8718 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_1,generation=1}
   [junit4]   2> 1226157 T8718 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1226162 T8718 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1226163 T8718 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1226163 T8718 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1226163 T8718 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1226163 T8718 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1226163 T8718 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1226163 T8718 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1226164 T8718 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1226164 T8718 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1226164 T8718 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1226164 T8718 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1226164 T8718 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1226170 T8718 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226171 T8718 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226172 T8718 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226173 T8718 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226176 T8718 oasc.PluginBag.createPlugin requestHandler : 
'lazy' created with startup=lazy 
   [junit4]   2> 1226178 T8718 oasc.RequestHandlers.initHandlersFromConfig 
Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1226179 T8718 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 1226180 T8718 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1226182 T8718 oasu.UpdateHandler.<init> Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1226182 T8718 oasu.UpdateLog.init Initializing UpdateLog: 
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 1226183 T8718 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1226183 T8718 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1226184 T8718 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=2.6767578125, 
floorSegmentMB=0.9052734375, forceMergeDeletesPctAllowed=5.951670679105333, 
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1226184 T8718 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_1,generation=1}
   [junit4]   2> 1226185 T8718 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1226185 T8718 oass.SolrIndexSearcher.<init> Opening 
Searcher@1df16ff[collection1] main
   [junit4]   2> 1226185 T8718 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1226185 T8718 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1226186 T8718 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1226186 T8718 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 1226196 T8718 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1226232 T8718 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1226233 T8718 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1226234 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 1226235 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: direct
   [junit4]   2> 1226235 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 1226235 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: multipleFields
   [junit4]   2> 1226235 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: jarowinkler
   [junit4]   2> 1226236 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: external
   [junit4]   2> 1226236 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: freq
   [junit4]   2> 1226236 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: fqcn
   [junit4]   2> 1226236 T8719 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: perDict
   [junit4]   2> 1226239 T8719 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1df16ff[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1226240 T8717 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 1226243 T8717 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime
   [junit4]   2> ASYNC  NEW_CORE C2451 name=collection1 
org.apache.solr.core.SolrCore@1d47a0c
   [junit4]   2> 1226245 T8717 C2451 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:* 
(-1495961545934372864)} 0 1
   [junit4]   2> 1226248 T8717 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1226249 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1226249 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1226289 T8717 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1226296 T8717 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1226313 T8717 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1226315 T8717 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1226319 T8717 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1226429 T8717 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1226436 T8717 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1226437 T8717 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1226441 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1226444 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1226445 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1226446 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1226447 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1226447 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1226447 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1226447 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1226448 T8717 oasc.CoreContainer.reload Reloading SolrCore 
'collection1' using configuration from instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1226448 T8717 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\
   [junit4]   2> 1226448 T8717 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1a4872a
   [junit4]   2> 1226448 T8717 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index/
   [junit4]   2> 1226453 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1226453 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1226453 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1226454 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1226454 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1226454 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1226454 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1226455 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1226455 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1226455 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1226456 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1226456 T8717 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1226457 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226458 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226459 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226460 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1226465 T8717 oasc.PluginBag.createPlugin requestHandler : 
'lazy' created with startup=lazy 
   [junit4]   2> 1226467 T8717 oasc.RequestHandlers.initHandlersFromConfig 
Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1226468 T8717 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 1226468 T8717 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1226469 T8717 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1226469 T8717 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1226469 T8717 oass.SolrIndexSearcher.<init> Opening 
Searcher@112569f[collection1] main
   [junit4]   2> 1226470 T8717 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1226470 T8717 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1226470 T8717 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1226470 T8717 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 1226472 T8717 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1226476 T8717 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1226477 T8717 oasu.DefaultSolrCoreState.newIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1226477 T8717 oasu.DefaultSolrCoreState.newIndexWriter Waiting 
until IndexWriter is unused... core=collection1
   [junit4]   2> 1226477 T8717 oasu.DefaultSolrCoreState.newIndexWriter Closing 
old IndexWriter... core=collection1
   [junit4]   2> 1226478 T8717 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1226478 T8717 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_1,generation=1}
   [junit4]   2> 1226482 T8717 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1226482 T8717 oasu.DefaultSolrCoreState.newIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1226482 T8717 oasc.SolrCore.getSearcher WARN [collection1] 
PERFORMANCE WARNING: Overlapping onDeckSearchers=2
   [junit4]   2> 1226482 T8717 oass.SolrIndexSearcher.<init> Opening 
Searcher@60abe4[collection1] main
   [junit4]   2> 1226483 T8717 oasc.CoreContainer.registerCore replacing core: 
collection1
   [junit4]   2> 1226483 T8717 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@1d47a0c
   [junit4]   2> 1226483 T8717 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> 1226484 T8717 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1226487 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 1226487 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: direct
   [junit4]   2> 1226487 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 1226487 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: multipleFields
   [junit4]   2> 1226487 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: jarowinkler
   [junit4]   2> 1226488 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: external
   [junit4]   2> 1226488 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: freq
   [junit4]   2> 1226488 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: fqcn
   [junit4]   2> 1226488 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: perDict
   [junit4]   2> 1226489 T8721 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@112569f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1226489 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 1226490 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: direct
   [junit4]   2> 1226490 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 1226490 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: multipleFields
   [junit4]   2> 1226490 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: jarowinkler
   [junit4]   2> 1226491 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: external
   [junit4]   2> 1226491 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: freq
   [junit4]   2> 1226491 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: fqcn
   [junit4]   2> 1226491 T8721 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: perDict
   [junit4]   2> 1226492 T8721 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@60abe4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1226501 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[529 (1495961546203856896)]} 0 1
   [junit4]   2> ASYNC  NEW_CORE C2452 name=collection1 
org.apache.solr.core.SolrCore@180c8c
   [junit4]   2> 1226501 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1227501 T8722 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1227503 T8722 oass.SolrIndexSearcher.<init> Opening 
Searcher@1454d51[collection1] main
   [junit4]   2> 1227503 T8722 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1227504 T8721 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1454d51[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
   [junit4]   2> 1227505 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[530 (1495961547255578624)]} 0 1005
   [junit4]   2> 1227505 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1227507 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=1 
   [junit4]   2> 1227507 T8717 C2452 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {delete=[529 
(-1495961547258724352)]} 0 0
   [junit4]   2> 1227508 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1228505 T8722 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1228506 T8722 oass.SolrIndexSearcher.<init> Opening 
Searcher@16f135a[collection1] main
   [junit4]   2> 1228506 T8722 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1228507 T8721 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@16f135a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C1)))}
   [junit4]   2> 1228508 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[550 (1495961548308348928)]} 0 2008
   [junit4]   2> 1228509 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1228510 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548310446080)]} 0 2010
   [junit4]   2> 1228511 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548310446081)]} 0 2011
   [junit4]   2> 1228511 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548311494656)]} 0 2011
   [junit4]   2> 1228511 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548311494657)]} 0 2011
   [junit4]   2> 1228512 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548312543232)]} 0 2012
   [junit4]   2> 1228512 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548312543233)]} 0 2012
   [junit4]   2> 1228513 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548313591808)]} 0 2013
   [junit4]   2> 1228513 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548313591809)]} 0 2013
   [junit4]   2> 1228513 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548313591810)]} 0 2013
   [junit4]   2> 1228514 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961548313591811)]} 0 2014
   [junit4]   2> 1228514 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1229508 T8722 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1229509 T8722 oass.SolrIndexSearcher.<init> Opening 
Searcher@761550[collection1] main
   [junit4]   2> 1229510 T8722 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1229511 T8721 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@761550[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C1)
 Uninverting(_2(6.0.0):C11/9:delGen=1)))}
   [junit4]   2> 1229515 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[531 (1495961549363216384)]} 0 3015
   [junit4]   2> 1229515 T8717 C2452 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1229516 T8717 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime
   [junit4]   2> 1229519 T8717 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs
   [junit4]   2> 1229523 T8717 C2452 oass.SolrIndexSearcher.<init> Opening 
Searcher@452f65[collection1] realtime
   [junit4]   2> 1229523 T8717 C2452 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:* 
(-1495961549368459264)} 0 4
   [junit4]   2> 1229524 T8717 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1229525 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1229525 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1229543 T8717 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1229549 T8717 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1229572 T8717 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1229573 T8717 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1229577 T8717 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1229646 T8717 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1229650 T8717 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1229652 T8717 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1229656 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1229658 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1229660 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1229660 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1229661 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1229661 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1229661 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1229662 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1229662 T8717 oasc.CoreContainer.reload Reloading SolrCore 
'collection1' using configuration from instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1229662 T8717 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\
   [junit4]   2> 1229662 T8717 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1a4872a
   [junit4]   2> 1229664 T8717 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index/
   [junit4]   2> 1229668 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1229669 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1229669 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1229669 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1229669 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1229670 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1229670 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1229670 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1229670 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1229671 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1229671 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1229671 T8717 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1229674 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229675 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229676 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229677 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229681 T8717 oasc.PluginBag.createPlugin requestHandler : 
'lazy' created with startup=lazy 
   [junit4]   2> 1229683 T8717 oasc.RequestHandlers.initHandlersFromConfig 
Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1229684 T8717 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 1229685 T8717 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1229686 T8717 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1229686 T8717 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1229686 T8717 oass.SolrIndexSearcher.<init> Opening 
Searcher@1e33ed6[collection1] main
   [junit4]   2> 1229687 T8717 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1229687 T8717 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1229687 T8717 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1229687 T8717 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 1229689 T8717 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1229693 T8717 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1229694 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 1229694 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: direct
   [junit4]   2> 1229694 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 1229694 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: multipleFields
   [junit4]   2> 1229695 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: jarowinkler
   [junit4]   2> 1229695 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: external
   [junit4]   2> 1229695 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: freq
   [junit4]   2> 1229695 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: fqcn
   [junit4]   2> 1229696 T8723 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: perDict
   [junit4]   2> 1229697 T8723 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1e33ed6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1229698 T8717 oasu.DefaultSolrCoreState.newIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1229698 T8717 oasu.DefaultSolrCoreState.newIndexWriter Waiting 
until IndexWriter is unused... core=collection1
   [junit4]   2> 1229698 T8717 oasu.DefaultSolrCoreState.newIndexWriter Closing 
old IndexWriter... core=collection1
   [junit4]   2> 1229698 T8717 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_2,generation=2}
   [junit4]   2> 1229699 T8717 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 1229699 T8717 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=87.1396484375, 
floorSegmentMB=1.59765625, forceMergeDeletesPctAllowed=18.651460242603427, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8803797084153694
   [junit4]   2> 1229700 T8717 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_2,generation=2}
   [junit4]   2> 1229700 T8717 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 1229700 T8717 oasu.DefaultSolrCoreState.newIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1229700 T8717 oass.SolrIndexSearcher.<init> Opening 
Searcher@b2d0fd[collection1] main
   [junit4]   2> 1229701 T8717 oasc.CoreContainer.registerCore replacing core: 
collection1
   [junit4]   2> 1229701 T8717 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@180c8c
   [junit4]   2> 1229701 T8717 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=3,autocommits=0,soft autocommit 
maxTime=1000ms,soft 
autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=14,adds=1,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=14,cumulative_deletesById=1,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 1229703 T8717 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1229711 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[0 (1495961549569785856)]} 0 0
   [junit4]   2> 1229712 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[1 (1495961549570834432)]} 0 1
   [junit4]   2> 1229712 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[2 (1495961549570834433)]} 0 1
   [junit4]   2> 1229712 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[3 (1495961549570834434)]} 0 1
   [junit4]   2> 1229713 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[4 (1495961549571883008)]} 0 2
   [junit4]   2> 1229713 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[5 (1495961549571883009)]} 0 2
   [junit4]   2> 1229713 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[6 (1495961549571883010)]} 0 2
   [junit4]   2> 1229713 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[7 (1495961549571883011)]} 0 2
   [junit4]   2> 1229714 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[8 (1495961549572931584)]} 0 3
   [junit4]   2> 1229714 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[9 (1495961549572931585)]} 0 3
   [junit4]   2> 1229714 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[10 (1495961549572931586)]} 0 3
   [junit4]   2> 1229715 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[11 (1495961549573980160)]} 0 4
   [junit4]   2> 1229716 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[12 (1495961549575028736)]} 0 5
   [junit4]   2> 1229716 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[13 (1495961549575028737)]} 0 5
   [junit4]   2> ASYNC  NEW_CORE C2453 name=collection1 
org.apache.solr.core.SolrCore@152ae2e
   [junit4]   2> 1229717 T8717 C2453 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=1 
   [junit4]   2> 1229723 T8723 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@b2d0fd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1229723 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[14 (1495961549576077312)]} 0 12
   [junit4]   2> 1229724 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[15 (1495961549583417344)]} 0 13
   [junit4]   2> 1229724 T8717 C2453 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1229724 T8717 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">0</int></lst><result name="response" 
numFound="0" start="0"></result>
   [junit4]   2>        </response>
   [junit4]   2>        
   [junit4]   2>                request 
was:q=id:14&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 1229725 T8717 oasc.SolrException.log ERROR REQUEST FAILED: 
q=id:14&qt=standard&start=0&rows=20&version=2.2: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">0</int></lst><result name="response" 
numFound="0" start="0"></result>
   [junit4]   2>        </response>
   [junit4]   2>        
   [junit4]   2>                request 
was:q=id:14&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2>                at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:787)
   [junit4]   2>                at 
org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:192)
   [junit4]   2>                at 
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2>                at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>                at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>                at 
java.lang.reflect.Method.invoke(Method.java:483)
   [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:53)
   [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:53)
   [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> 1229726 T8717 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest 
-Dtests.method=testMaxDocs -Dtests.seed=3761A067B2F5825D -Dtests.slow=true 
-Dtests.locale=zh_HK -Dtests.timezone=Europe/Monaco -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.21s J1 | AutoCommitTest.testMaxDocs <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during 
query
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3761A067B2F5825D:8EE076B89E1F86D7]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:794)
   [junit4]    >        at 
org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:192)
   [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">0</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]    > </response>
   [junit4]    >        request 
was:q=id:14&qt=standard&start=0&rows=20&version=2.2
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:787)
   [junit4]    >        ... 40 more
   [junit4]   2> 1229736 T8724 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1229736 T8717 oas.SolrTestCaseJ4.setUp ###Starting 
testCommitWithin
   [junit4]   2> 1229738 T8724 oass.SolrIndexSearcher.<init> Opening 
Searcher@e1fd8a[collection1] main
   [junit4]   2> 1229738 T8724 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1229739 T8723 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@e1fd8a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):C16)))}
   [junit4]   2> 1229741 T8717 C2453 oass.SolrIndexSearcher.<init> Opening 
Searcher@1be55c5[collection1] realtime
   [junit4]   2> 1229741 T8717 C2453 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:* 
(-1495961549597048832)} 0 4
   [junit4]   2> 1229742 T8717 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1229742 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1229743 T8717 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1229759 T8717 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1229809 T8717 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1229827 T8717 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1229828 T8717 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1229831 T8717 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1229897 T8717 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1229902 T8717 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1229903 T8717 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1229908 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1229911 T8717 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1229912 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1229913 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1229913 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1229913 T8717 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1229914 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1229914 T8717 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1229914 T8717 oasc.CoreContainer.reload Reloading SolrCore 
'collection1' using configuration from instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1229914 T8717 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\
   [junit4]   2> 1229915 T8717 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1a4872a
   [junit4]   2> 1229915 T8717 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index/
   [junit4]   2> 1229919 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1229919 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1229919 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1229919 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1229920 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1229920 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1229920 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1229921 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1229921 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1229921 T8717 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1229922 T8717 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1229922 T8717 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1229923 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229924 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229925 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1229926 T8717 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1230575 T8717 oasc.PluginBag.createPlugin requestHandler : 
'lazy' created with startup=lazy 
   [junit4]   2> 1230579 T8717 oasc.RequestHandlers.initHandlersFromConfig 
Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1230580 T8717 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 1230580 T8717 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1230582 T8717 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1230582 T8717 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1230582 T8717 oass.SolrIndexSearcher.<init> Opening 
Searcher@1c8b09c[collection1] main
   [junit4]   2> 1230583 T8717 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1230583 T8717 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1230583 T8717 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1230584 T8717 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 1230585 T8717 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1230590 T8717 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1230590 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 1230590 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: direct
   [junit4]   2> 1230591 T8717 oasu.DefaultSolrCoreState.newIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1230591 T8717 oasu.DefaultSolrCoreState.newIndexWriter Waiting 
until IndexWriter is unused... core=collection1
   [junit4]   2> 1230591 T8717 oasu.DefaultSolrCoreState.newIndexWriter Closing 
old IndexWriter... core=collection1
   [junit4]   2> 1230591 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 1230591 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: multipleFields
   [junit4]   2> 1230591 T8717 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_3,generation=3}
   [junit4]   2> 1230592 T8717 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 1230592 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: jarowinkler
   [junit4]   2> 1230593 T8717 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=32, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1230593 T8717 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_3,generation=3}
   [junit4]   2> 1230594 T8717 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 1230594 T8717 oasu.DefaultSolrCoreState.newIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1230594 T8717 oasc.SolrCore.getSearcher WARN [collection1] 
PERFORMANCE WARNING: Overlapping onDeckSearchers=2
   [junit4]   2> 1230594 T8717 oass.SolrIndexSearcher.<init> Opening 
Searcher@1cc682d[collection1] main
   [junit4]   2> 1230594 T8717 oasc.CoreContainer.registerCore replacing core: 
collection1
   [junit4]   2> 1230595 T8717 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@152ae2e
   [junit4]   2> 1230595 T8717 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=1,autocommits=0,soft autocommit maxDocs=14,soft 
autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=16,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=16,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 1230595 T8717 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1230598 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: external
   [junit4]   2> 1230598 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: freq
   [junit4]   2> 1230599 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: fqcn
   [junit4]   2> 1230599 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: perDict
   [junit4]   2> 1230600 T8725 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1c8b09c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1230600 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 1230600 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: direct
   [junit4]   2> 1230600 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 1230600 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: multipleFields
   [junit4]   2> 1230601 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: jarowinkler
   [junit4]   2> 1230601 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: external
   [junit4]   2> 1230601 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: freq
   [junit4]   2> 1230601 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: fqcn
   [junit4]   2> 1230602 T8725 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: perDict
   [junit4]   2> 1230602 T8725 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1cc682d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1230612 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[529 (1495961550512455680)]} 0 2
   [junit4]   2> ASYNC  NEW_CORE C2454 name=collection1 
org.apache.solr.core.SolrCore@148b94
   [junit4]   2> 1230612 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1234788 T8726 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1234790 T8726 oass.SolrIndexSearcher.<init> Opening 
Searcher@1b25e99[collection1] main
   [junit4]   2> 1234790 T8726 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1234791 T8725 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1b25e99[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.0.0):C1)))}
   [junit4]   2> 1235065 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[530 (1495961555183861760)]} 0 4455
   [junit4]   2> 1235066 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1235067 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[529 (-1495961555185958912)]} 0 4457
   [junit4]   2> 1235067 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1236168 T8726 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1236171 T8726 oass.SolrIndexSearcher.<init> Opening 
Searcher@6865cc[collection1] main
   [junit4]   2> 1236172 T8725 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6865cc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(6.0.0):C1)))}
   [junit4]   2> 1236172 T8726 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1236445 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1236445 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1236447 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556632993792)]} 0 5837
   [junit4]   2> 1236447 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556632993793)]} 0 5837
   [junit4]   2> 1236447 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556632993794)]} 0 5837
   [junit4]   2> 1236448 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556634042368)]} 0 5838
   [junit4]   2> 1236449 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556634042369)]} 0 5839
   [junit4]   2> 1236449 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556635090944)]} 0 5839
   [junit4]   2> 1236449 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556635090945)]} 0 5839
   [junit4]   2> 1236450 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556636139520)]} 0 5840
   [junit4]   2> 1236450 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556636139521)]} 0 5840
   [junit4]   2> 1236450 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{add=[500 (1495961556636139522)]} 0 5840
   [junit4]   2> 1236452 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=2 
   [junit4]   2> 1236452 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556638236672)]} 0 5842
   [junit4]   2> 1236452 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556638236673)]} 0 5842
   [junit4]   2> 1236453 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556639285248)]} 0 5843
   [junit4]   2> 1236453 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556639285249)]} 0 5843
   [junit4]   2> 1236453 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556639285250)]} 0 5843
   [junit4]   2> 1236453 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556639285251)]} 0 5843
   [junit4]   2> 1236454 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556639285252)]} 0 5844
   [junit4]   2> 1236454 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556640333824)]} 0 5844
   [junit4]   2> 1236454 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556640333825)]} 0 5844
   [junit4]   2> 1236454 T8717 oasup.LogUpdateProcessor.finish [collection1] 
{delete=[530 (-1495961556640333826)]} 0 5844
   [junit4]   2> 1236455 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1237553 T8726 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1237554 T8726 oass.SolrIndexSearcher.<init> Opening 
Searcher@ec5f0c[collection1] main
   [junit4]   2> 1237554 T8726 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1237555 T8725 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@ec5f0c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(6.0.0):C10/9:delGen=1)))}
   [junit4]   2> 1237555 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1237556 T8717 C2454 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} 
hits=0 status=0 QTime=0 
   [junit4]   2> 1237556 T8717 oas.SolrTestCaseJ4.tearDown ###Ending 
testCommitWithin
   [junit4]   2> 1237557 T8717 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 1237557 T8717 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=30050290
   [junit4]   2> 1237557 T8717 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@148b94
   [junit4]   2> 1237558 T8717 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=3,autocommits=0,soft 
autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=12,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=12,cumulative_deletesById=11,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 1237558 T8717 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1237558 T8717 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1237558 T8717 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1237558 T8717 C2454 oasu.DirectUpdateHandler2.closeWriter 
Committing on IndexWriter close.
   [junit4]   2> 1237559 T8717 C2454 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@8cb332 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@386a6a),segFN=segments_4,generation=4}
   [junit4]   2> 1237559 T8717 C2454 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 4
   [junit4]   2> 1237560 T8717 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1237572 T8717 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1237572 T8717 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index;done=false>>]
   [junit4]   2> 1237573 T8717 oasc.CachingDirectoryFactory.close Closing 
directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\index
   [junit4]   2> 1237573 T8717 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\ 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\;done=false>>]
   [junit4]   2> 1237573 T8717 oasc.CachingDirectoryFactory.close Closing 
directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001\init-core-data-001\
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest
 3761A067B2F5825D-001
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=3, maxDocsPerChunk=453, blockSize=34), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=3, blockSize=34)), 
sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=zh_HK, 
timezone=Europe/Monaco
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_31 
(32-bit)/cpus=3,threads=1,free=238783264,total=381419520
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestCloudManagedSchemaConcurrent, FullSolrCloudDistribCmdsTest, TestZkChroot, 
TestFastWriter, TestSchemaSimilarityResource, SegmentsInfoRequestHandlerTest, 
TestExpandComponent, EchoParamsTest, HighlighterTest, TestFastOutputStream, 
CachingDirectoryFactoryTest, TestFieldTypeCollectionResource, FileUtilsTest, 
AsyncMigrateRouteKeyTest, SolrCmdDistributorTest, UpdateParamsTest, 
OverseerCollectionProcessorTest, TestSolrJ, CurrencyFieldOpenExchangeTest, 
TestSolrQueryParserResource, TestSolrCoreProperties, 
DocExpirationUpdateProcessorFactoryTest, WordBreakSolrSpellCheckerTest, 
BasicZkTest, TestSolrQueryParser, StatsComponentTest, HdfsDirectoryFactoryTest, 
HdfsLockFactoryTest, ZkStateWriterTest, DirectSolrSpellCheckerTest, 
TestSearcherReuse, BasicFunctionalityTest, LoggingHandlerTest, IndexSchemaTest, 
TestCursorMarkWithoutUniqueKey, LeaderInitiatedRecoveryOnCommitTest, 
DistributedFacetPivotSmallTest, TestRandomMergePolicy, TestValueSourceCache, 
TestConfigReload, ReturnFieldsTest, TestPHPSerializedResponseWriter, 
TestSolrConfigHandler, TestJoin, SolrRequestParserTest, 
DistributedTermsComponentTest, TestDynamicLoading, TestRestManager, 
TermVectorComponentDistributedTest, ChaosMonkeySafeLeaderTest, TestBlobHandler, 
InfoHandlerTest, ExitableDirectoryReaderTest, TestBlendedInfixSuggestions, 
RAMDirectoryFactoryTest, ZkNodePropsTest, TestDistributedGrouping, 
SchemaVersionSpecificBehaviorTest, TestTolerantSearch, 
TestSimpleTrackingShardHandler, PolyFieldTest, BinaryUpdateRequestHandlerTest, 
CopyFieldTest, TestCloudManagedSchema, HdfsSyncSliceTest, TestRandomFaceting, 
SSLMigrationTest, UpdateRequestProcessorFactoryTest, 
CollectionsAPIDistributedZkTest, HdfsBasicDistributedZk2Test, 
DistributedMLTComponentTest, TestFiltering, TestSolrDeletionPolicy1, TestTrie, 
TermsComponentTest, QueryEqualityTest, OverseerStatusTest, 
TestAnalyzeInfixSuggestions, TestDefaultSearchFieldResource, TestHdfsUpdateLog, 
MinimalSchemaTest, TestLMJelinekMercerSimilarityFactory, ReplicationFactorTest, 
TestSchemaVersionResource, TestInitParams, SharedFSAutoReplicaFailoverTest, 
DateMathParserTest, TestCoreDiscovery, DistributedFacetPivotLargeTest, 
HdfsWriteToMultipleCollectionsTest, TestReload, TestObjectReleaseTracker, 
StandardRequestHandlerTest, DebugComponentTest, DocumentBuilderTest, 
LeaderElectionIntegrationTest, TestMissingGroups, CustomCollectionTest, 
PrimitiveFieldTypeTest, SolrIndexConfigTest, RegexBoostProcessorTest, 
TestSolrDynamicMBean, TestCodecSupport, BadIndexSchemaTest, 
SpellingQueryConverterTest, TestCopyFieldCollectionResource, 
ShardRoutingCustomTest, HttpPartitionTest, DistributedFacetPivotLongTailTest, 
TestSuggestSpellingConverter, TestBulkSchemaAPI, PluginInfoTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, ZkCLITest, AutoCommitTest]
   [junit4] Completed on J1 in 11.91s, 3 tests, 1 error <<< FAILURES!

[...truncated 698 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958:
 There were test failures: 475 suites, 1890 tests, 1 error, 57 ignored (30 
assumptions)

Total time: 51 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_31 -server 
-XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to