Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4793/
Java: 32bit/jdk1.8.0_45 -server -XX:+UseG1GC

4 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.core.TestLazyCores

Error Message:
ERROR: SolrIndexSearcher opens=51 closes=50

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50
        at __randomizedtesting.SeedInfo.seed([A976D68BA4F8464B]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:472)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
        at sun.reflect.GeneratedMethodAccessor30.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  junit.framework.TestSuite.org.apache.solr.core.TestLazyCores

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:     1) 
Thread[id=9409, name=searcherExecutor-5059-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]         at sun.misc.Unsafe.park(Native Method)        
 at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)         
at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
        at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.core.TestLazyCores: 
   1) Thread[id=9409, name=searcherExecutor-5059-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([A976D68BA4F8464B]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.core.TestLazyCores

Error Message:
There are still zombie threads that couldn't be terminated:    1) 
Thread[id=9409, name=searcherExecutor-5059-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]         at sun.misc.Unsafe.park(Native Method)        
 at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)         
at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
        at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   1) Thread[id=9409, name=searcherExecutor-5059-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([A976D68BA4F8464B]:0)


FAILED:  org.apache.solr.update.AutoCommitTest.testMaxTime

Error Message:
expected:<2> but was:<3>

Stack Trace:
java.lang.AssertionError: expected:<2> but was:<3>
        at 
__randomizedtesting.SeedInfo.seed([A976D68BA4F8464B:3382AB693A62DA77]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:250)
        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:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10432 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> Creating dataDir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001
   [junit4]   2> 1005275 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1005276 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1005276 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1005276 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1005277 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1005322 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 1005330 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1005350 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1005351 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1005356 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1005444 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1005454 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1005456 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1005462 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1005463 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1005465 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1005465 WARN  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1005466 WARN  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1005466 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1005466 WARN  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1005466 WARN  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1005466 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1005466 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr
   [junit4]   2> 1005466 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\'
   [junit4]   2> 1005487 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 13937574
   [junit4]   2> 1005488 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\]
   [junit4]   2> 1005488 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib
   [junit4]   2> 1005488 WARN  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib).
   [junit4]   2> 1005497 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1005502 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1005503 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] o.a.s.l.LogWatcher 
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1005503 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] o.a.s.l.LogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1005504 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1005505 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] o.a.s.c.SolrCore 
Created CoreDescriptor: {name=collection1, config=solrconfig.xml, 
transient=false, schema=schema.xml, loadOnStartup=true, 
instanceDir=collection1, collection=collection1, 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001,
 shard=shard1}
   [junit4]   2> 1005505 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1005505 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1005506 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1005530 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1005535 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1005555 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1005556 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1005561 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1005635 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1005641 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1005642 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1005647 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1005649 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1005651 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1005652 WARN  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1005652 WARN  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1005652 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1005652 WARN  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1005652 WARN  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1005653 INFO  (coreLoadExecutor-3563-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1005653 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1005653 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\],
 dataDir=[null]
   [junit4]   2> 1005653 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af9ce0
   [junit4]   2> 1005653 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\
   [junit4]   2> 1005653 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\index/
   [junit4]   2> 1005654 WARN  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\index'
 doesn't exist. Creating new index...
   [junit4]   2> 1005654 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\index
   [junit4]   2> 1005654 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=80.375, 
floorSegmentMB=1.365234375, forceMergeDeletesPctAllowed=13.659599634575654, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6900968073377524
   [junit4]   2> 1005655 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@25a514 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76ad0b),segFN=segments_1,generation=1}
   [junit4]   2> 1005655 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1005655 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\
   [junit4]   2> 1005659 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1005659 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1005660 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1005667 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005667 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005669 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005670 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005675 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 1005677 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers 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> 1005677 WARN  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated 
<admin/gettableFiles>, Please update your config to use the 
ShowFileRequestHandler.
   [junit4]   2> 1005677 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1005678 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1005679 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1005680 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1005680 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1005680 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5414221489488903]
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@25a514 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76ad0b),segFN=segments_1,generation=1}
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1681b33[collection1] 
main
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1005681 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1005690 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1005717 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1005717 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1005718 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 1005718 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1005718 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1005718 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1005718 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 1005718 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 1005718 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1005718 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1503637533502734336
   [junit4]   2> 1005719 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1005719 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1005719 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1005719 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1005719 INFO  
(searcherExecutor-3564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1681b33[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1005720 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest 
version 1503637533502734336
   [junit4]   2> 1005720 INFO  (coreLoadExecutor-3563-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1005723 INFO  
(SUITE-AutoCommitTest-seed#[A976D68BA4F8464B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1005726 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 1005727 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{deleteByQuery=*:* (-1503637533511122944)} 0 1
   [junit4]   2> 1005730 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1005731 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1005731 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1005754 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1005777 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1005797 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1005797 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1005803 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1005874 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1005880 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1005881 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1005887 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1005888 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1005891 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1005891 WARN  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1005891 WARN  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1005891 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1005892 WARN  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1005892 WARN  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1005892 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1005892 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\],
 
dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\]
   [junit4]   2> 1005892 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af9ce0
   [junit4]   2> 1005892 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\index/
   [junit4]   2> 1005893 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\
   [junit4]   2> 1005896 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1005897 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1005897 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1005897 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1005897 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1005897 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1005897 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1005898 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1005898 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1005898 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1005898 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1005898 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1005899 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005900 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005901 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005902 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1005907 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 1005909 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.RequestHandlers 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> 1005910 WARN  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated 
<admin/gettableFiles>, Please update your config to use the 
ShowFileRequestHandler.
   [junit4]   2> 1005911 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1005911 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1005911 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1005912 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@d4b2d2[collection1] 
main
   [junit4]   2> 1005912 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1005912 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 1005912 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1005912 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1005913 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1005919 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1005920 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1005920 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1005920 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1005920 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1005920 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1005920 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 1005920 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 1005920 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 1005920 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1005921 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1005921 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1005921 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1005922 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d4b2d2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1005922 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest 
version 1503637533511122944
   [junit4]   2> 1005922 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
   [junit4]   2> 1005923 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is 
unused... core=collection1
   [junit4]   2> 1005923 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... 
core=collection1
   [junit4]   2> 1005923 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=44, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1005923 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@25a514 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76ad0b),segFN=segments_1,generation=1}
   [junit4]   2> 1005923 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1005923 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1005924 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1ba1ac[collection1] 
main
   [junit4]   2> 1005924 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.CoreContainer replacing core: collection1
   [junit4]   2> 1005924 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1628f74
   [junit4]   2> 1005924 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.UpdateHandler 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> 1005924 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1005925 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ba1ac[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1005934 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[529 
(1503637533729226752)]} 0 0
   [junit4]   2> 1005935 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1009122 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1009129 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@8bcdb8[collection1] 
main
   [junit4]   2> 1009130 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1009131 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@8bcdb8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C1)))}
   [junit4]   2> 1009326 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[530 
(1503637537284947968)]} 0 3392
   [junit4]   2> 1009326 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1009327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[529 
(-1503637537287045120)]} 0 3393
   [junit4]   2> 1009327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1010125 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1010128 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1e7276f[collection1] 
main
   [junit4]   2> 1010128 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1010129 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e7276f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.3.0):C1)))}
   [junit4]   2> 1010324 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1010324 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1010326 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538333523968)]} 0 4392
   [junit4]   2> 1010326 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538334572544)]} 0 4392
   [junit4]   2> 1010326 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538334572545)]} 0 4392
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538334572546)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538335621120)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538335621121)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538335621122)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538335621123)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538335621124)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 
(1503637538335621125)]} 0 4393
   [junit4]   2> 1010327 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1010328 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538336669696)]} 0 4394
   [junit4]   2> 1010328 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538336669697)]} 0 4394
   [junit4]   2> 1010328 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538336669698)]} 0 4394
   [junit4]   2> 1010328 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538336669699)]} 0 4394
   [junit4]   2> 1010328 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538336669700)]} 0 4394
   [junit4]   2> 1010329 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538336669701)]} 0 4395
   [junit4]   2> 1010329 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538337718272)]} 0 4395
   [junit4]   2> 1010329 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538337718273)]} 0 4395
   [junit4]   2> 1010329 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538337718274)]} 0 4395
   [junit4]   2> 1010329 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 
(-1503637538337718275)]} 0 4395
   [junit4]   2> 1010329 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1011125 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1011126 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@153c601[collection1] 
main
   [junit4]   2> 1011126 INFO  (commitScheduler-3572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1011127 INFO  
(searcherExecutor-3569-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@153c601[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.3.0):C10/9:delGen=1)))}
   [junit4]   2> 1011128 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 
QTime=1 
   [junit4]   2> 1011128 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.c.S.Request [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> 1011128 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[A976D68BA4F8464B]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> 1011130 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
   [junit4]   2> 1011131 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@2ee292[collection1] realtime
   [junit4]   2> 1011131 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{deleteByQuery=*:* (-1503637539177627648)} 0 1
   [junit4]   2> 1011132 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1011133 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1011133 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1011167 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1011174 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1011185 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1011185 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1011188 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1011255 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1011262 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1011262 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1011266 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1011268 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1011269 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1011269 WARN  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1011269 WARN  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1011269 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1011269 WARN  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1011270 WARN  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1011270 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1011270 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\],
 
dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\]
   [junit4]   2> 1011270 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af9ce0
   [junit4]   2> 1011272 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\index/
   [junit4]   2> 1011276 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_A976D68BA4F8464B-001\init-core-data-001\
   [junit4]   2> 1011276 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1011276 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1011276 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1011276 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"uniq-fields"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1011277 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1011278 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating 
implicit default
   [junit4]   2> 1011282 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011283 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011284 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011285 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011290 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1011292 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.RequestHandlers 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> 1011292 WARN  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please 
update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1011293 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@15f933d[collection1] main
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1011294 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1011295 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1011300 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1011301 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1011301 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1011302 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1011302 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1011303 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15f933d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1011304 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version 
buckets from index
   [junit4]   2> 1011304 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket 
highest value from index
   [junit4]   2> 1011305 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.UpdateLog Took 4 ms to seed version buckets with highest version 
1503637539177627648
   [junit4]   2> 1011305 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
   [junit4]   2> 1011305 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... 
core=collection1
   [junit4]   2> 1011305 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
   [junit4]   2> 1011305 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@25a514 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76ad0b),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@25a514 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76ad0b),segFN=segments_2,generation=2}
   [junit4]   2> 1011305 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1011306 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=63.3583984375, 
floorSegmentMB=1.9150390625, forceMergeDeletesPctAllowed=2.548976518466045, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1011306 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@25a514 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76ad0b),segFN=segments_2,generation=2}
   [junit4]   2> 1011306 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1011307 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1011307 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1a483b6[collection1] main
   [junit4]   2> 1011307 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.CoreContainer replacing core: collection1
   [junit4]   2> 1011307 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@97b2e1
   [junit4]   2> 1011307 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=3,autocommits=0,soft 
autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=12,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=12,cumulative_deletesById=11,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 1011308 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a483b6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1011308 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1011316 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[0 (1503637539372662784)]} 0 1
   [junit4]   2> 1011316 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[1 (1503637539372662785)]} 0 1
   [junit4]   2> 1011317 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[2 (1503637539373711360)]} 0 2
   [junit4]   2> 1011317 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[3 (1503637539373711361)]} 0 2
   [junit4]   2> 1011317 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[4 (1503637539373711362)]} 0 2
   [junit4]   2> 1011317 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[5 (1503637539373711363)]} 0 2
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[6 (1503637539374759936)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[7 (1503637539374759937)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[8 (1503637539374759938)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[9 (1503637539374759939)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[10 (1503637539374759940)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[11 (1503637539374759941)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[12 (1503637539374759942)]} 0 3
   [junit4]   2> 1011318 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[13 (1503637539374759943)]} 0 3
   [junit4]   2> 1011319 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 
   [junit4]   2> 1011320 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[14 (1503637539375808512)]} 0 5
   [junit4]   2> 1011322 INFO  (commitScheduler-3576-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1011324 INFO  (commitScheduler-3576-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@101a84c[collection1] 
main
   [junit4]   2> 1011324 INFO  (commitScheduler-3576-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1011325 INFO  
(searcherExecutor-3573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@101a84c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.3.0):C15)))}
   [junit4]   2> 1011519 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] {add=[15 (1503637539585523712)]} 0 
204
   [junit4]   2> 1011520 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 
QTime=1 
   [junit4]   2> 1011520 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0 
   [junit4]   2> 1011521 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
   [junit4]   2> 1011522 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
   [junit4]   2> 1011527 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@660a64[collection1] realtime
   [junit4]   2> 1011528 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{deleteByQuery=*:* (-1503637539589718016)} 0 5
   [junit4]   2> 1011528 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1011529 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1011529 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1011545 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1011550 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
   [junit4]   2> 1011568 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1011569 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1011575 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1011628 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1011631 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1011632 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1011636 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1011638 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1011639 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1011640 WARN  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1011640 WARN  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1011640 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1011640 WARN  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1011640 WARN  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1011640 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1011641 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[A976D68BA4F8464B]) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\],
 
dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\sol

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

concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Jun 11, 2015 12:05:35 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=9409, name=searcherExecutor-5059-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]   2> Jun 11, 2015 12:05:38 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=9409, name=searcherExecutor-5059-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]   2>         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{v_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 v_s=Lucene50(blocksize=128), v_f=FSTOrd50}, docValues:{}, 
sim=DefaultSimilarity, locale=ar_SY, timezone=America/Fort_Wayne
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_45 
(32-bit)/cpus=3,threads=2,free=271207224,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: 
[DistributedSpellCheckComponentTest, TestDefaultSearchFieldResource, 
TestGroupingSearch, TestMaxScoreQueryParser, SuggesterTest, SolrCoreTest, 
TestDynamicFieldResource, MergeStrategyTest, StressHdfsTest, 
DistributedQueryElevationComponentTest, ZkCLITest, TestConfigSets, 
TestDistributedSearch, ConnectionManagerTest, TestIBSimilarityFactory, 
RuleEngineTest, RecoveryAfterSoftCommitTest, TestDocumentBuilder, 
TestManagedSchemaFieldResource, ConcurrentDeleteAndCreateCollectionTest, 
PingRequestHandlerTest, BasicDistributedZkTest, TestElisionMultitermQuery, 
CoreAdminHandlerTest, TestConfigOverlay, TestCollationFieldDocValues, 
AddBlockUpdateTest, PeerSyncTest, TestShardHandlerFactory, CacheHeaderTest, 
DocumentAnalysisRequestHandlerTest, TestSchemaManager, 
TestNamedUpdateProcessors, AnalysisErrorHandlingTest, TestReloadAndDeleteDocs, 
ZkControllerTest, SharedFSAutoReplicaFailoverUtilsTest, TestQueryUtils, 
DOMUtilTest, TestArbitraryIndexDir, TestComplexPhraseQParserPlugin, 
TestDistributedMissingSort, URLClassifyProcessorTest, SimpleFacetsTest, 
TestStressVersions, SolrCoreCheckLockOnStartupTest, 
PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, TestChildDocTransformer, 
CachingDirectoryFactoryTest, SolrIndexSplitterTest, DeleteReplicaTest, 
TestSchemaNameResource, TestJmxIntegration, TestReplicationHandler, 
CoreMergeIndexesAdminHandlerTest, DirectUpdateHandlerTest, SoftAutoCommitTest, 
MultiThreadedOCPTest, NoCacheHeaderTest, SpatialRPTFieldTypeTest, 
TestOverriddenPrefixQueryForCustomFieldType, TestDefaultStatsCache, 
ShardRoutingTest, QueryResultKeyTest, SolrPluginUtilsTest, 
TestPerFieldSimilarity, TestUpdate, SortByFunctionTest, JavabinLoaderTest, 
TestCoreContainer, TestExpandComponent, TestRecoveryHdfs, PreAnalyzedFieldTest, 
DocExpirationUpdateProcessorFactoryTest, TestBlendedInfixSuggestions, 
SolrInfoMBeanTest, TestFastLRUCache, TestRandomDVFaceting, 
TestExactSharedStatsCache, IndexSchemaRuntimeFieldTest, 
DirectSolrConnectionTest, TestRebalanceLeaders, TestSweetSpotSimilarityFactory, 
HighlighterMaxOffsetTest, HdfsLockFactoryTest, CursorMarkTest, 
TestManagedResource, XmlUpdateRequestHandlerTest, TestCharFilters, 
AnalysisAfterCoreReloadTest, SOLR749Test, TestFieldResource, 
SolrTestCaseJ4Test, VMParamsZkACLAndCredentialsProvidersTest, 
TestTrackingShardHandlerFactory, SuggesterWFSTTest, TestAnalyzedSuggestions, 
DocValuesTest, HdfsChaosMonkeySafeLeaderTest, 
TestDistributedStatsComponentCardinality, TestStressRecovery, TestLFUCache, 
DistanceFunctionTest, TestRequestStatusCollectionAPI, TestOmitPositions, 
TestWriterPerf, DistributedIntervalFacetingTest, CollectionStateFormat2Test, 
HdfsUnloadDistributedZkTest, TestReRankQParserPlugin, 
TestSchemaVersionResource, FieldAnalysisRequestHandlerTest, 
DistributedFacetPivotSmallAdvancedTest, TestFreeTextSuggestions, 
TestCloudManagedSchema, TestSchemaSimilarityResource, 
ShowFileRequestHandlerTest, CleanupOldIndexTest, ChaosMonkeySafeLeaderTest, 
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, OverseerTest, 
RecoveryZkTest, ZkSolrClientTest, TestFaceting, TestRecovery, 
TestHashPartitioner, TestRangeQuery, StatsComponentTest, TestLazyCores]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestLazyCores 
-Dtests.seed=A976D68BA4F8464B -Dtests.slow=true -Dtests.locale=ar_SY 
-Dtests.timezone=America/Fort_Wayne -Dtests.asserts=true 
-Dtests.file.encoding=Cp1252
   [junit4] ERROR   0.00s J0 | TestLazyCores (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ERROR: 
SolrIndexSearcher opens=51 closes=50
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A976D68BA4F8464B]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:472)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.core.TestLazyCores: 
   [junit4]    >    1) Thread[id=9409, name=searcherExecutor-5059-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A976D68BA4F8464B]:0)Throwable #3: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=9409, name=searcherExecutor-5059-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A976D68BA4F8464B]:0)
   [junit4] Completed [375/500] on J0 in 146.42s, 7 tests, 1 failure, 2 errors 
<<< FAILURES!

[...truncated 391 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:536: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:484: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:230: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:511:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1438:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:993:
 There were test failures: 500 suites, 1979 tests, 3 suite-level errors, 1 
failure, 361 ignored (34 assumptions)

Total time: 50 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to