You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/04/25 08:14:41 UTC
[JENKINS] Lucene-Solr-5.x-Windows (32bit/jdk1.8.0_40) - Build #
4605 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4605/
Java: 32bit/jdk1.8.0_40 -server -XX:+UseParallelGC
3 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([F910913F8B0DB153]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
at sun.reflect.GeneratedMethodAccessor37.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=3438, name=searcherExecutor-2475-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=3438, name=searcherExecutor-2475-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([F910913F8B0DB153]: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=3438, name=searcherExecutor-2475-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=3438, name=searcherExecutor-2475-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([F910913F8B0DB153]:0)
Build Log:
[...truncated 10280 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\init-core-data-001
[junit4] 2> 607435 T3396 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 607437 T3396 oas.SolrTestCaseJ4.setUp ###Starting testCreateSame
[junit4] 2> 607482 T3396 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\'
[junit4] 2> 607509 T3396 oasc.CoreContainer.<init> New CoreContainer 30902485
[junit4] 2> 607510 T3396 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\]
[junit4] 2> 607511 T3396 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\lib
[junit4] 2> 607511 T3396 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\lib).
[junit4] 2> 607524 T3396 oashc.HttpShardHandlerFactory.init created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 607530 T3396 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 607531 T3396 oasc.SolrCores.allocateLazyCores Allocating transient cache for 4 transient cores
[junit4] 2> 607533 T3396 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 607534 T3396 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 607534 T3396 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 607538 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, instanceDir=collection1, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\, schema=schema.xml, transient=false, dataDir=data\}
[junit4] 2> 607539 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy2, config=solrconfig.xml, instanceDir=collection2, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 607540 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy3, config=solrconfig.xml, instanceDir=collection3, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection3\, schema=schema.xml, transient=on, dataDir=data\}
[junit4] 2> 607540 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy4, config=solrconfig.xml, instanceDir=collection4, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\, schema=schema.xml, transient=false, dataDir=data\}
[junit4] 2> 607541 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy5, config=solrconfig.xml, instanceDir=collection5, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\, schema=schema.xml, transient=false, dataDir=data\}
[junit4] 2> 607542 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy6, config=solrconfig.xml, instanceDir=collection6, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 607542 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy7, config=solrconfig.xml, instanceDir=collection7, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection7\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 607544 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy8, config=solrconfig.xml, instanceDir=collection8, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection8\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 607545 T3396 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy9, config=solrconfig.xml, instanceDir=collection9, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection9\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 607546 T3398 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\'
[junit4] 2> 607546 T3399 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\'
[junit4] 2> 607572 T3399 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 607572 T3398 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 607573 T3399 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 607574 T3398 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 607577 T3399 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 607577 T3398 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 607578 T3399 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\conf\schema.xml
[junit4] 2> 607578 T3398 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\conf\schema.xml
[junit4] 2> 607580 T3399 oass.IndexSchema.readSchema [collectionLazy5] Schema name=tiny
[junit4] 2> 607580 T3398 oass.IndexSchema.readSchema [collectionLazy2] Schema name=tiny
[junit4] 2> 607584 T3399 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 607584 T3399 oasc.CoreContainer.create Creating SolrCore 'collectionLazy5' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\
[junit4] 2> 607584 T3399 c:collectionLazy5 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 607585 T3399 c:collectionLazy5 oasc.SolrCore.<init> [[collectionLazy5] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\], dataDir=[null]
[junit4] 2> 607585 T3399 c:collectionLazy5 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collectionLazy5
[junit4] 2> 607584 T3398 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 607585 T3398 oasc.CoreContainer.create Creating SolrCore 'collectionLazy2' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\
[junit4] 2> 607585 T3399 c:collectionLazy5 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\
[junit4] 2> 607585 T3399 c:collectionLazy5 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\index/
[junit4] 2> 607585 T3398 c:collectionLazy2 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 607586 T3399 c:collectionLazy5 oasc.SolrCore.initIndex WARN [collectionLazy5] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\index' doesn't exist. Creating new index...
[junit4] 2> 607586 T3398 c:collectionLazy2 oasc.SolrCore.<init> [[collectionLazy2] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\], dataDir=[null]
[junit4] 2> 607586 T3399 c:collectionLazy5 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\index
[junit4] 2> 607586 T3398 c:collectionLazy2 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collectionLazy2
[junit4] 2> 607587 T3398 c:collectionLazy2 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\
[junit4] 2> 607587 T3398 c:collectionLazy2 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\index/
[junit4] 2> 607587 T3399 c:collectionLazy5 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5184305390863775]
[junit4] 2> 607587 T3398 c:collectionLazy2 oasc.SolrCore.initIndex WARN [collectionLazy2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\index' doesn't exist. Creating new index...
[junit4] 2> 607587 T3398 c:collectionLazy2 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\index
[junit4] 2> 607588 T3398 c:collectionLazy2 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5184305390863775]
[junit4] 2> 607588 T3398 c:collectionLazy2 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@101a032 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c9b8d8),segFN=segments_1,generation=1}
[junit4] 2> 607588 T3398 c:collectionLazy2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607591 T3399 c:collectionLazy5 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12648fe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19d2994),segFN=segments_1,generation=1}
[junit4] 2> 607591 T3399 c:collectionLazy5 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607592 T3398 c:collectionLazy2 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 607593 T3399 c:collectionLazy5 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 607596 T3398 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607597 T3398 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607598 T3399 c:collectionLazy5 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607598 T3398 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607599 T3399 c:collectionLazy5 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607599 T3398 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607600 T3399 c:collectionLazy5 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607601 T3398 c:collectionLazy2 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 607602 T3398 c:collectionLazy2 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 607602 T3399 c:collectionLazy5 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607603 T3398 c:collectionLazy2 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 607603 T3398 c:collectionLazy2 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 607604 T3398 c:collectionLazy2 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1768571472, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 607604 T3399 c:collectionLazy5 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 607605 T3399 c:collectionLazy5 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 607605 T3398 c:collectionLazy2 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@101a032 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c9b8d8),segFN=segments_1,generation=1}
[junit4] 2> 607605 T3398 c:collectionLazy2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607605 T3398 c:collectionLazy2 oass.SolrIndexSearcher.<init> Opening Searcher@1b95401[collectionLazy2] main
[junit4] 2> 607605 T3399 c:collectionLazy5 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 607605 T3399 c:collectionLazy5 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 607606 T3398 c:collectionLazy2 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\conf
[junit4] 2> 607606 T3398 c:collectionLazy2 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\conf}
[junit4] 2> 607606 T3398 c:collectionLazy2 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\conf
[junit4] 2> 607607 T3398 c:collectionLazy2 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 607607 T3398 c:collectionLazy2 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 607607 T3398 c:collectionLazy2 oasc.SolrCores.putTransientCore Opening transient core collectionLazy2
[junit4] 2> 607608 T3398 c:collectionLazy2 oasc.CoreContainer.registerCore registering core: collectionLazy2
[junit4] 2> 607615 T3399 c:collectionLazy5 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1768571472, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 607615 T3397 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\'
[junit4] 2> 607615 T3400 c:collectionLazy2 oasc.SolrCore.registerSearcher [collectionLazy2] Registered new searcher Searcher@1b95401[collectionLazy2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 607621 T3399 c:collectionLazy5 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12648fe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19d2994),segFN=segments_1,generation=1}
[junit4] 2> 607621 T3399 c:collectionLazy5 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607621 T3399 c:collectionLazy5 oass.SolrIndexSearcher.<init> Opening Searcher@13152f4[collectionLazy5] main
[junit4] 2> 607622 T3399 c:collectionLazy5 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\conf
[junit4] 2> 607622 T3399 c:collectionLazy5 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\conf}
[junit4] 2> 607622 T3399 c:collectionLazy5 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\conf
[junit4] 2> 607622 T3399 c:collectionLazy5 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 607623 T3399 c:collectionLazy5 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 607623 T3399 c:collectionLazy5 oasc.CoreContainer.registerCore registering core: collectionLazy5
[junit4] 2> 607623 T3401 c:collectionLazy5 oasc.SolrCore.registerSearcher [collectionLazy5] Registered new searcher Searcher@13152f4[collectionLazy5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 607635 T3397 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 607637 T3397 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 607640 T3397 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 607641 T3397 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\conf\schema.xml
[junit4] 2> 607644 T3397 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 607645 T3397 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 607646 T3397 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\
[junit4] 2> 607646 T3397 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 607646 T3397 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\], dataDir=[null]
[junit4] 2> 607646 T3397 c:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 607647 T3397 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\
[junit4] 2> 607650 T3397 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\index/
[junit4] 2> 607650 T3397 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 607650 T3397 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\index
[junit4] 2> 607651 T3397 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5184305390863775]
[junit4] 2> 607651 T3397 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13bc1fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19a494e),segFN=segments_1,generation=1}
[junit4] 2> 607651 T3397 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607653 T3397 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 607654 T3397 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607655 T3397 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607656 T3397 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607657 T3397 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607678 T3397 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 607679 T3397 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 607681 T3397 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 607681 T3397 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 607682 T3397 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1768571472, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 607682 T3397 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13bc1fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19a494e),segFN=segments_1,generation=1}
[junit4] 2> 607682 T3397 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607682 T3397 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@6cb324[collection1] main
[junit4] 2> 607683 T3397 c:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\conf
[junit4] 2> 607683 T3397 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\conf}
[junit4] 2> 607683 T3397 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\conf
[junit4] 2> 607684 T3397 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 607684 T3397 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 607685 T3397 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 607714 T3402 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6cb324[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 607715 T3396 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\'
[junit4] 2> 607731 T3396 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 607733 T3396 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 607736 T3396 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 607737 T3396 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\conf\schema.xml
[junit4] 2> 607739 T3396 oass.IndexSchema.readSchema [collectionLazy4] Schema name=tiny
[junit4] 2> 607741 T3396 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 607741 T3396 oasc.CoreContainer.create Creating SolrCore 'collectionLazy4' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\
[junit4] 2> 607741 T3396 c:collectionLazy4 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 607742 T3396 c:collectionLazy4 oasc.SolrCore.<init> [[collectionLazy4] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\], dataDir=[null]
[junit4] 2> 607742 T3396 c:collectionLazy4 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collectionLazy4
[junit4] 2> 607742 T3396 c:collectionLazy4 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\
[junit4] 2> 607743 T3396 c:collectionLazy4 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\index/
[junit4] 2> 607743 T3396 c:collectionLazy4 oasc.SolrCore.initIndex WARN [collectionLazy4] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\index' doesn't exist. Creating new index...
[junit4] 2> 607743 T3396 c:collectionLazy4 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\index
[junit4] 2> 607745 T3396 c:collectionLazy4 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=3.6572265625, floorSegmentMB=1.78125, forceMergeDeletesPctAllowed=29.307954286296635, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49963975626855683
[junit4] 2> 607745 T3396 c:collectionLazy4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e52a15 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19b4a03),segFN=segments_1,generation=1}
[junit4] 2> 607745 T3396 c:collectionLazy4 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607748 T3396 c:collectionLazy4 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 607749 T3396 c:collectionLazy4 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607750 T3396 c:collectionLazy4 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607751 T3396 c:collectionLazy4 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607752 T3396 c:collectionLazy4 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607773 T3396 c:collectionLazy4 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 607773 T3396 c:collectionLazy4 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 607774 T3396 c:collectionLazy4 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 607774 T3396 c:collectionLazy4 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 607775 T3396 c:collectionLazy4 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3215511664260965]
[junit4] 2> 607775 T3396 c:collectionLazy4 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e52a15 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19b4a03),segFN=segments_1,generation=1}
[junit4] 2> 607775 T3396 c:collectionLazy4 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607775 T3396 c:collectionLazy4 oass.SolrIndexSearcher.<init> Opening Searcher@18e1080[collectionLazy4] main
[junit4] 2> 607776 T3396 c:collectionLazy4 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\conf
[junit4] 2> 607776 T3396 c:collectionLazy4 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\conf}
[junit4] 2> 607777 T3396 c:collectionLazy4 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\conf
[junit4] 2> 607777 T3396 c:collectionLazy4 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 607777 T3396 c:collectionLazy4 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 607777 T3396 c:collectionLazy4 oasc.CoreContainer.registerCore registering core: collectionLazy4
[junit4] 2> 607778 T3396 c:collectionLazy4 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\'
[junit4] 2> 607778 T3404 c:collectionLazy4 oasc.SolrCore.registerSearcher [collectionLazy4] Registered new searcher Searcher@18e1080[collectionLazy4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 607795 T3396 c:collectionLazy4 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 607815 T3396 c:collectionLazy4 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 607818 T3396 c:collectionLazy4 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 607818 T3396 c:collectionLazy4 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\conf\schema.xml
[junit4] 2> 607820 T3396 c:collectionLazy4 oass.IndexSchema.readSchema [collectionLazy6] Schema name=tiny
[junit4] 2> 607823 T3396 c:collectionLazy4 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 607823 T3396 c:collectionLazy4 oasc.CoreContainer.create Creating SolrCore 'collectionLazy6' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\
[junit4] 2> 607823 T3396 c:collectionLazy6 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 607824 T3396 c:collectionLazy6 oasc.SolrCore.<init> [[collectionLazy6] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\], dataDir=[null]
[junit4] 2> 607824 T3396 c:collectionLazy6 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collectionLazy6
[junit4] 2> 607824 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\
[junit4] 2> 607824 T3396 c:collectionLazy6 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\index/
[junit4] 2> 607824 T3396 c:collectionLazy6 oasc.SolrCore.initIndex WARN [collectionLazy6] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\index' doesn't exist. Creating new index...
[junit4] 2> 607827 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\index
[junit4] 2> 607827 T3396 c:collectionLazy6 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 607828 T3396 c:collectionLazy6 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e87186 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7da63f),segFN=segments_1,generation=1}
[junit4] 2> 607828 T3396 c:collectionLazy6 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607830 T3396 c:collectionLazy6 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 607832 T3396 c:collectionLazy6 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607833 T3396 c:collectionLazy6 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607834 T3396 c:collectionLazy6 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607835 T3396 c:collectionLazy6 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 607852 T3396 c:collectionLazy6 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 607852 T3396 c:collectionLazy6 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 607855 T3396 c:collectionLazy6 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 607856 T3396 c:collectionLazy6 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 607856 T3396 c:collectionLazy6 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1910702709, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 607857 T3396 c:collectionLazy6 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e87186 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7da63f),segFN=segments_1,generation=1}
[junit4] 2> 607857 T3396 c:collectionLazy6 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 607857 T3396 c:collectionLazy6 oass.SolrIndexSearcher.<init> Opening Searcher@ee295a[collectionLazy6] main
[junit4] 2> 607858 T3396 c:collectionLazy6 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\conf
[junit4] 2> 607858 T3396 c:collectionLazy6 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\conf}
[junit4] 2> 607858 T3396 c:collectionLazy6 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\conf
[junit4] 2> 607859 T3396 c:collectionLazy6 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 607860 T3396 c:collectionLazy6 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 607860 T3406 c:collectionLazy6 oasc.SolrCore.registerSearcher [collectionLazy6] Registered new searcher Searcher@ee295a[collectionLazy6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 607860 T3396 c:collectionLazy6 oasc.SolrCores.putTransientCore Opening transient core collectionLazy6
[junit4] 2> 607860 T3396 c:collectionLazy6 oasc.CoreContainer.registerCore registering core: collectionLazy6
[junit4] 2> 607886 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction core create command schema=schema.xml&dataDir=t12&name=collectionLazy2&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 607887 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy2, config=solrconfig.xml, instanceDir=collectionLazy2, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collectionLazy2\, schema=schema.xml, transient=false, dataDir=t12}
[junit4] 2> 607887 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction WARN Creating a core with existing name is not allowed
[junit4] 2> 607888 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction core create command schema=schema.xml&dataDir=t14&name=collectionLazy4&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 607888 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy4, config=solrconfig.xml, instanceDir=collectionLazy4, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collectionLazy4\, schema=schema.xml, transient=false, dataDir=t14}
[junit4] 2> 607889 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction WARN Creating a core with existing name is not allowed
[junit4] 2> 607889 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction core create command schema=schema.xml&dataDir=t15&name=collectionLazy5&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 607890 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy5, config=solrconfig.xml, instanceDir=collectionLazy5, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collectionLazy5\, schema=schema.xml, transient=false, dataDir=t15}
[junit4] 2> 607890 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction WARN Creating a core with existing name is not allowed
[junit4] 2> 607891 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction core create command schema=schema.xml&dataDir=t16&name=collectionLazy6&action=CREATE&config=solrconfig.xml&wt=xml
[junit4] 2> 607891 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy6, config=solrconfig.xml, instanceDir=collectionLazy6, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collectionLazy6\, schema=schema.xml, transient=false, dataDir=t16}
[junit4] 2> 607891 T3396 c:collectionLazy6 oasha.CoreAdminHandler.handleCreateAction WARN Creating a core with existing name is not allowed
[junit4] 2> 607892 T3396 c:collectionLazy6 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=30902485
[junit4] 2> 607896 T3396 c:collectionLazy6 oasc.SolrCore.close [collectionLazy5] CLOSING SolrCore org.apache.solr.core.SolrCore@105246c
[junit4] 2> 607896 T3396 c:collectionLazy6 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 607896 T3396 c:collectionLazy6 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 607897 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 607897 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 607899 T3396 c:collectionLazy6 oasc.SolrCore.closeSearcher [collectionLazy5] Closing main searcher on request.
[junit4] 2> 607899 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 607900 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\index;done=false>>]
[junit4] 2> 607900 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\index
[junit4] 2> 607900 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\;done=false>>]
[junit4] 2> 607900 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection5\data\
[junit4] 2> 607901 T3396 c:collectionLazy6 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@fb08ba
[junit4] 2> 607901 T3396 c:collectionLazy6 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 607901 T3396 c:collectionLazy6 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 607901 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 607901 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\index;done=false>>]
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\index
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\;done=false>>]
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection1\data\
[junit4] 2> 607902 T3396 c:collectionLazy6 oasc.SolrCore.close [collectionLazy4] CLOSING SolrCore org.apache.solr.core.SolrCore@cfa847
[junit4] 2> 607902 T3396 c:collectionLazy6 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 607903 T3396 c:collectionLazy6 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 607903 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 607903 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 607904 T3396 c:collectionLazy6 oasc.SolrCore.closeSearcher [collectionLazy4] Closing main searcher on request.
[junit4] 2> 607904 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 607904 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\;done=false>>]
[junit4] 2> 607904 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\
[junit4] 2> 607905 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\index;done=false>>]
[junit4] 2> 607905 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection4\data\index
[junit4] 2> 607905 T3396 c:collectionLazy6 oasc.SolrCore.close [collectionLazy2] CLOSING SolrCore org.apache.solr.core.SolrCore@18ae87d
[junit4] 2> 607905 T3396 c:collectionLazy6 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 607905 T3396 c:collectionLazy6 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 607906 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 607906 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 607909 T3396 c:collectionLazy6 oasc.SolrCore.closeSearcher [collectionLazy2] Closing main searcher on request.
[junit4] 2> 607910 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 607910 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\;done=false>>]
[junit4] 2> 607910 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\
[junit4] 2> 607910 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\index;done=false>>]
[junit4] 2> 607911 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection2\data\index
[junit4] 2> 607911 T3396 c:collectionLazy6 oasc.SolrCore.close [collectionLazy6] CLOSING SolrCore org.apache.solr.core.SolrCore@1108be4
[junit4] 2> 607911 T3396 c:collectionLazy6 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 607911 T3396 c:collectionLazy6 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 607911 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 607912 T3396 c:collectionLazy6 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 607942 T3396 c:collectionLazy6 oasc.SolrCore.closeSearcher [collectionLazy6] Closing main searcher on request.
[junit4] 2> 607942 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 607942 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\;done=false>>]
[junit4] 2> 607943 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\
[junit4] 2> 607943 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\index;done=false>>]
[junit4] 2> 607943 T3396 c:collectionLazy6 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-001\collection6\data\index
[junit4] 2> 607943 T3396 c:collectionLazy6 oas.SolrTestCaseJ4.tearDown ###Ending testCreateSame
[junit4] 2> 607948 T3396 c:collectionLazy6 oas.SolrTestCaseJ4.setUp ###Starting testCreateTransientFromAdmin
[junit4] 2> 608026 T3396 c:collectionLazy6 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\'
[junit4] 2> 608049 T3396 c:collectionLazy6 oasc.CoreContainer.<init> New CoreContainer 6064657
[junit4] 2> 608050 T3396 c:collectionLazy6 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\]
[junit4] 2> 608050 T3396 c:collectionLazy6 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\lib
[junit4] 2> 608050 T3396 c:collectionLazy6 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\lib).
[junit4] 2> 608058 T3396 c:collectionLazy6 oashc.HttpShardHandlerFactory.init created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 608062 T3396 c:collectionLazy6 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 608062 T3396 c:collectionLazy6 oasc.SolrCores.allocateLazyCores Allocating transient cache for 4 transient cores
[junit4] 2> 608062 T3396 c:collectionLazy6 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 608063 T3396 c:collectionLazy6 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 608063 T3396 c:collectionLazy6 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 608064 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, instanceDir=collection1, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\, schema=schema.xml, transient=false, dataDir=data\}
[junit4] 2> 608065 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy2, config=solrconfig.xml, instanceDir=collection2, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 608065 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy3, config=solrconfig.xml, instanceDir=collection3, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection3\, schema=schema.xml, transient=on, dataDir=data\}
[junit4] 2> 608071 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy4, config=solrconfig.xml, instanceDir=collection4, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection4\, schema=schema.xml, transient=false, dataDir=data\}
[junit4] 2> 608072 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy5, config=solrconfig.xml, instanceDir=collection5, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\, schema=schema.xml, transient=false, dataDir=data\}
[junit4] 2> 608073 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy6, config=solrconfig.xml, instanceDir=collection6, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection6\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 608073 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy7, config=solrconfig.xml, instanceDir=collection7, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection7\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 608074 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy8, config=solrconfig.xml, instanceDir=collection8, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection8\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 608074 T3396 c:collectionLazy6 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collectionLazy9, config=solrconfig.xml, instanceDir=collection9, loadOnStartup=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection9\, schema=schema.xml, transient=true, dataDir=data\}
[junit4] 2> 608076 T3410 c:collectionLazy6 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\'
[junit4] 2> 608077 T3408 c:collectionLazy6 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\'
[junit4] 2> 608078 T3409 c:collectionLazy6 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\'
[junit4] 2> 608101 T3408 c:collectionLazy6 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 608103 T3408 c:collectionLazy6 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 608105 T3408 c:collectionLazy6 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 608105 T3408 c:collectionLazy6 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\conf\schema.xml
[junit4] 2> 608106 T3410 c:collectionLazy6 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 608107 T3409 c:collectionLazy6 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 608108 T3408 c:collectionLazy6 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 608108 T3410 c:collectionLazy6 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 608109 T3409 c:collectionLazy6 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 608111 T3408 c:collectionLazy6 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 608112 T3408 c:collectionLazy6 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\
[junit4] 2> 608112 T3408 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 608112 T3410 c:collectionLazy6 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 608112 T3409 c:collectionLazy6 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 608113 T3409 c:collectionLazy6 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\conf\schema.xml
[junit4] 2> 608112 T3408 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\], dataDir=[null]
[junit4] 2> 608114 T3408 c:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 608112 T3410 c:collectionLazy6 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\conf\schema.xml
[junit4] 2> 608114 T3408 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\data\
[junit4] 2> 608115 T3409 c:collectionLazy6 oass.IndexSchema.readSchema [collectionLazy2] Schema name=tiny
[junit4] 2> 608116 T3410 c:collectionLazy6 oass.IndexSchema.readSchema [collectionLazy5] Schema name=tiny
[junit4] 2> 608117 T3409 c:collectionLazy6 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 608117 T3409 c:collectionLazy6 oasc.CoreContainer.create Creating SolrCore 'collectionLazy2' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\
[junit4] 2> 608117 T3409 c:collectionLazy2 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 608117 T3409 c:collectionLazy2 oasc.SolrCore.<init> [[collectionLazy2] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\], dataDir=[null]
[junit4] 2> 608117 T3409 c:collectionLazy2 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collectionLazy2
[junit4] 2> 608117 T3410 c:collectionLazy6 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 608119 T3410 c:collectionLazy6 oasc.CoreContainer.create Creating SolrCore 'collectionLazy5' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\
[junit4] 2> 608119 T3409 c:collectionLazy2 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\data\
[junit4] 2> 608119 T3409 c:collectionLazy2 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\data\index/
[junit4] 2> 608119 T3409 c:collectionLazy2 oasc.SolrCore.initIndex WARN [collectionLazy2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\data\index' doesn't exist. Creating new index...
[junit4] 2> 608119 T3410 c:collectionLazy5 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 608119 T3409 c:collectionLazy2 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\data\index
[junit4] 2> 608120 T3409 c:collectionLazy2 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5184305390863775]
[junit4] 2> 608119 T3410 c:collectionLazy5 oasc.SolrCore.<init> [[collectionLazy5] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\], dataDir=[null]
[junit4] 2> 608120 T3410 c:collectionLazy5 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collectionLazy5
[junit4] 2> 608120 T3409 c:collectionLazy2 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13a9d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fd41a2),segFN=segments_1,generation=1}
[junit4] 2> 608120 T3409 c:collectionLazy2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 608120 T3410 c:collectionLazy5 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\data\
[junit4] 2> 608122 T3409 c:collectionLazy2 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 608123 T3408 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\data\index/
[junit4] 2> 608123 T3408 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 608123 T3408 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\data\index
[junit4] 2> 608124 T3408 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5184305390863775]
[junit4] 2> 608124 T3408 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f95d5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@45d1ee),segFN=segments_1,generation=1}
[junit4] 2> 608124 T3408 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 608126 T3408 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 608127 T3409 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608127 T3408 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608127 T3409 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608128 T3408 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608128 T3409 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608129 T3408 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608129 T3409 c:collectionLazy2 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608131 T3408 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 608141 T3409 c:collectionLazy2 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 608149 T3409 c:collectionLazy2 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 608149 T3408 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 608149 T3408 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 608149 T3409 c:collectionLazy2 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 608150 T3409 c:collectionLazy2 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 608150 T3409 c:collectionLazy2 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1768571472, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 608150 T3409 c:collectionLazy2 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13a9d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fd41a2),segFN=segments_1,generation=1}
[junit4] 2> 608150 T3409 c:collectionLazy2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 608151 T3409 c:collectionLazy2 oass.SolrIndexSearcher.<init> Opening Searcher@567d11[collectionLazy2] main
[junit4] 2> 608151 T3409 c:collectionLazy2 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\conf
[junit4] 2> 608151 T3409 c:collectionLazy2 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\conf}
[junit4] 2> 608152 T3409 c:collectionLazy2 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection2\conf
[junit4] 2> 608152 T3408 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 608152 T3408 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 608141 T3410 c:collectionLazy5 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\data\index/
[junit4] 2> 608152 T3409 c:collectionLazy2 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 608152 T3408 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1768571472, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 608152 T3410 c:collectionLazy5 oasc.SolrCore.initIndex WARN [collectionLazy5] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection5\data\index' doesn't exist. Creating new index...
[junit4] 2> 608153 T3408 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f95d5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@45d1ee),segFN=segments_1,generation=1}
[junit4] 2> 608153 T3408 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 608152 T3409 c:collectionLazy2 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 608153 T3409 c:collectionLazy2 oasc.SolrCores.putTransientCore Opening transient core collectionLazy2
[junit4] 2> 608153 T3408 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@d87558[collection1] main
[junit4] 2> 608153 T3409 c:collectionLazy2 oasc.CoreContainer.registerCore registering core: collectionLazy2
[junit4] 2> 608155 T3408 c:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\conf
[junit4] 2> 608155 T3408 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-002\collection1\conf}
[junit4] 2> 608155 T3408 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLa
[...truncated too long message...]
B0DB153-001\tempDir-007\collection2\data\;done=false>>]
[junit4] 2> 612145 T3396 c:collectionLazy4 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestLazyCores F910913F8B0DB153-001\tempDir-007\collection2\data\
[junit4] 2> 612145 T3396 c:collectionLazy4 oas.SolrTestCaseJ4.tearDown ###Ending testLazySearch
[junit4] 2> 612146 T3396 c:collectionLazy4 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 612146 T3396 c:collectionLazy4 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=18312718
[junit4] 2> 735944 T3396 c:collectionLazy4 oas.SolrTestCaseJ4.endTrackingSearchers ERROR ERROR: SolrIndexSearcher opens=51 closes=50
[junit4] 2> 128673 T3395 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 148709 T3395 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
[junit4] 2> 1) Thread[id=3438, name=searcherExecutor-2475-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> 148709 T3395 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=3438, name=searcherExecutor-2475-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> 151943 T3395 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=3438, name=searcherExecutor-2475-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=Lucene50(blocksize=128), id=Lucene50(blocksize=128), v_s=PostingsFormat(name=Memory doPackFST= false), v_f=PostingsFormat(name=Direct)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=sv_SE, timezone=EAT
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40 (32-bit)/cpus=3,threads=2,free=70520528,total=304611328
[junit4] 2> NOTE: All tests run in this JVM: [BasicDistributedZkTest, TestJmxMonitoredMap, TestOmitPositions, TestUniqueKeyFieldResource, OverriddenZkACLAndCredentialsProvidersTest, RequestHandlersTest, TestCollationField, TestSolrQueryParserResource, TestSchemaResource, SoftAutoCommitTest, TestImplicitCoreProperties, TestShortCircuitedRequests, TestGroupingSearch, HighlighterMaxOffsetTest, CoreAdminCreateDiscoverTest, NoCacheHeaderTest, TestCloudSchemaless, TestFastLRUCache, ParsingFieldUpdateProcessorsTest, QueryResultKeyTest, NumericFieldsTest, TestFieldResource, ScriptEngineTest, TestSort, TestFreeTextSuggestions, SuggesterWFSTTest, TestManagedSchemaDynamicFieldResource, TestIBSimilarityFactory, CircularListTest, SolrInfoMBeanTest, CoreAdminHandlerTest, TestReplicationHandler, DocValuesMissingTest, TestArbitraryIndexDir, TestChildDocTransformer, SortByFunctionTest, SimpleFacetsTest, TestStressLucene, SampleTest, UnloadDistributedZkTest, TestBinaryResponseWriter, TestAnalyzedSuggestions, TestBlendedInfixSuggestions, EnumFieldTest, ZkCLITest, TestSolrXml, HardAutoCommitTest, UniqFieldsUpdateProcessorFactoryTest, HdfsThreadLeakTest, TestQueryUtils, BJQParserTest, HdfsBasicDistributedZkTest, TestWriterPerf, TestAddFieldRealTimeGet, TestFunctionQuery, DateRangeFieldTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrConfigHandler, TestConfigOverlay, PingRequestHandlerTest, AssignTest, TestSweetSpotSimilarityFactory, TestDynamicLoading, TestOverriddenPrefixQueryForCustomFieldType, DistributedExpandComponentTest, SynonymTokenizerTest, TestFileDictionaryLookup, RecoveryZkTest, SolrCoreCheckLockOnStartupTest, TestManagedSynonymFilterFactory, TestCollationFieldDocValues, TestSortingResponseWriter, TestManagedSchema, TestDynamicFieldResource, TestDocumentBuilder, BadComponentTest, TestStressReorder, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=F910913F8B0DB153 -Dtests.slow=true -Dtests.locale=sv_SE -Dtests.timezone=EAT -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestLazyCores (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50
[junit4] > at __randomizedtesting.SeedInfo.seed([F910913F8B0DB153]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
[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=3438, name=searcherExecutor-2475-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([F910913F8B0DB153]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=3438, name=searcherExecutor-2475-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([F910913F8B0DB153]:0)
[junit4] Completed [188/484] on J0 in 151.97s, 7 tests, 1 failure, 2 errors <<< FAILURES!
[...truncated 911 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:536: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:484: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:229: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:511: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1434: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:991: There were test failures: 484 suites, 1946 tests, 3 suite-level errors, 961 ignored (24 assumptions)
Total time: 40 minutes 41 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
[JENKINS] Lucene-Solr-5.x-Windows (32bit/jdk1.8.0_40) - Build #
4606 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4606/
Java: 32bit/jdk1.8.0_40 -client -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.search.stats.TestDefaultStatsCache.test
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:51126/l_/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:51126/l_/collection1
at __randomizedtesting.SeedInfo.seed([25670BDEF88252D:8A024F67417448D5]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153)
at org.apache.solr.BaseDistributedSearchTestCase.index_specific(BaseDistributedSearchTestCase.java:534)
at org.apache.solr.search.stats.TestDefaultStatsCache.test(TestDefaultStatsCache.java:62)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:170)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
at sun.security.ssl.InputRecord.read(InputRecord.java:503)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:961)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:918)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
... 49 more
Build Log:
[...truncated 11044 lines...]
[junit4] Suite: org.apache.solr.search.stats.TestDefaultStatsCache
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\init-core-data-001
[junit4] 2> 2419794 T14245 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 2419794 T14245 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /l_/
[junit4] 2> 2420295 T14245 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1
[junit4] 2> 2420299 T14245 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2420318 T14245 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2420322 T14245 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:51117
[junit4] 2> 2420322 T14245 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/l_, hostPort=51117, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores}
[junit4] 2> 2420332 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2420332 T14245 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\'
[junit4] 2> 2420377 T14245 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\solr.xml
[junit4] 2> 2420391 T14245 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores
[junit4] 2> 2420392 T14245 oasc.CoreContainer.<init> New CoreContainer 20145653
[junit4] 2> 2420393 T14245 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\]
[junit4] 2> 2420393 T14245 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\lib
[junit4] 2> 2420393 T14245 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\lib).
[junit4] 2> 2420403 T14245 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2420413 T14245 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2420419 T14245 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2420419 T14245 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2420420 T14245 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2420422 T14245 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores
[junit4] 2> 2420424 T14245 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\, configSet=collection1, dataDir=data\}
[junit4] 2> 2420424 T14245 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\
[junit4] 2> 2420426 T14245 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2420426 T14255 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2420428 T14255 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2420428 T14255 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2420494 T14255 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2420507 T14255 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 2420543 T14255 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2420544 T14255 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 2420572 T14255 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2420808 T14255 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2420831 T14255 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2420834 T14255 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2420874 T14255 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2420879 T14255 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2420884 T14255 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2420885 T14255 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2420885 T14255 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2420886 T14255 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2420886 T14255 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2420887 T14255 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2420887 T14255 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2420887 T14255 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2420888 T14255 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
[junit4] 2> 2420888 T14255 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a831
[junit4] 2> 2420890 T14255 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 2420890 T14255 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index/
[junit4] 2> 2420890 T14255 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2420891 T14255 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index
[junit4] 2> 2420891 T14255 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.9765625, floorSegmentMB=1.482421875, forceMergeDeletesPctAllowed=4.42548809326272, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2420892 T14255 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@21e91f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32ca53),segFN=segments_1,generation=1}
[junit4] 2> 2420892 T14255 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2420903 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2420904 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2420905 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2420906 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2420907 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2420907 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2420908 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2420908 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2420909 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2420909 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2420910 T14255 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2420910 T14255 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2420917 T14255 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2420927 T14255 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2420930 T14255 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2420934 T14255 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2420949 T14255 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2420956 T14255 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 2420958 T14255 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 2420960 T14255 c:collection1 oasc.SolrCore.initStatsCache Using statsCache impl: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2420963 T14255 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2420963 T14255 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 2420966 T14255 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2420966 T14255 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2420968 T14255 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7766536415391114]
[junit4] 2> 2420970 T14255 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@21e91f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32ca53),segFN=segments_1,generation=1}
[junit4] 2> 2420970 T14255 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2420972 T14255 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@1037485[collection1] main
[junit4] 2> 2420972 T14255 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 2420972 T14255 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 2420973 T14255 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2420973 T14255 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 2421007 T14255 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2421184 T14255 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2421185 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 2421185 T14255 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2421185 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 2421186 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 2421186 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 2421187 T14245 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2421188 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2421188 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2421191 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 2421191 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 2421192 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 2421193 T14256 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 2421195 T14256 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1037485[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2421735 T14245 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 2421739 T14245 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2421810 T14245 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2421823 T14245 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:51120
[junit4] 2> 2421830 T14245 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/l_, hostPort=51120, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores}
[junit4] 2> 2421837 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2421837 T14245 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\'
[junit4] 2> 2421885 T14245 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\solr.xml
[junit4] 2> 2421897 T14245 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores
[junit4] 2> 2421897 T14245 oasc.CoreContainer.<init> New CoreContainer 18895036
[junit4] 2> 2421898 T14245 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\]
[junit4] 2> 2421898 T14245 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\lib
[junit4] 2> 2421899 T14245 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\lib).
[junit4] 2> 2421913 T14245 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2421922 T14245 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2421929 T14245 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2421930 T14245 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2421930 T14245 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2421946 T14245 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores
[junit4] 2> 2421949 T14245 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\, configSet=collection1, dataDir=data\}
[junit4] 2> 2421949 T14245 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\
[junit4] 2> 2421950 T14245 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2421954 T14267 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2421956 T14267 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2421956 T14267 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2422022 T14267 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2422037 T14267 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 2422078 T14267 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2422079 T14267 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 2422092 T14267 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2422303 T14267 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2422318 T14267 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2422321 T14267 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2422338 T14267 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2422343 T14267 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2422346 T14267 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2422348 T14267 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2422348 T14267 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2422348 T14267 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2422348 T14267 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2422348 T14267 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2422348 T14267 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2422348 T14267 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2422349 T14267 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
[junit4] 2> 2422349 T14267 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a831
[junit4] 2> 2422350 T14267 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 2422350 T14267 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index/
[junit4] 2> 2422350 T14267 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2422351 T14267 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index
[junit4] 2> 2422352 T14267 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.9765625, floorSegmentMB=1.482421875, forceMergeDeletesPctAllowed=4.42548809326272, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2422354 T14267 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@404420 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13ca96e),segFN=segments_1,generation=1}
[junit4] 2> 2422354 T14267 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2422363 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2422364 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2422364 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2422364 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2422365 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2422365 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2422365 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2422365 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2422367 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2422367 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2422367 T14267 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2422367 T14267 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2422368 T14267 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2422368 T14267 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2422369 T14267 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2422372 T14267 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2422390 T14267 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2422401 T14267 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 2422402 T14267 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 2422404 T14267 c:collection1 oasc.SolrCore.initStatsCache Using statsCache impl: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2422406 T14267 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2422407 T14267 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 2422407 T14267 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2422407 T14267 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2422408 T14267 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7766536415391114]
[junit4] 2> 2422410 T14267 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@404420 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13ca96e),segFN=segments_1,generation=1}
[junit4] 2> 2422410 T14267 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2422411 T14267 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@1a24a54[collection1] main
[junit4] 2> 2422411 T14267 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 2422412 T14267 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 2422412 T14267 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2422412 T14267 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 2422465 T14267 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2422659 T14267 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2422663 T14267 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2422666 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 2422669 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 2422669 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 2422669 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 2422675 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2422677 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 2422680 T14245 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2422682 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2422682 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 2422684 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 2422687 T14268 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 2422691 T14268 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a24a54[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2422697 T14245 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> ASYNC NEW_CORE C2478 name=collection1 org.apache.solr.core.SolrCore@13b8ef0
[junit4] 2> 2422867 T14250 c:collection1 C2478 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499421300966293504)} 0 5
[junit4] 2> ASYNC NEW_CORE C2479 name=collection1 org.apache.solr.core.SolrCore@e43d0
[junit4] 2> 2422997 T14266 c:collection1 C2479 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499421301103656960)} 0 5
[junit4] 2> 2423017 T14249 c:collection1 C2478 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={wt=javabin&version=2} {add=[0 (1499421301117288448)]} 0 15
[junit4] 2> 2423029 T14262 c:collection1 C2479 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={wt=javabin&version=2} {add=[0 (1499421301136162816)]} 0 5
[junit4] 2> 2423037 T14252 c:collection1 C2478 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2423062 T14252 c:collection1 C2478 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@21e91f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32ca53),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@21e91f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32ca53),segFN=segments_2,generation=2}
[junit4] 2> 2423063 T14252 c:collection1 C2478 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2423071 T14252 c:collection1 C2478 oass.SolrIndexSearcher.<init> Opening Searcher@104e5c7[collection1] main
[junit4] 2> 2423073 T14252 c:collection1 C2478 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2423082 T14256 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@104e5c7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1)))}
[junit4] 2> 2423102 T14252 c:collection1 C2478 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 66
[junit4] 2> 2423108 T14261 c:collection1 C2479 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2423147 T14261 c:collection1 C2479 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@404420 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13ca96e),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@404420 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13ca96e),segFN=segments_2,generation=2}
[junit4] 2> 2423148 T14261 c:collection1 C2479 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2423150 T14261 c:collection1 C2479 oass.SolrIndexSearcher.<init> Opening Searcher@11abf95[collection1] main
[junit4] 2> 2423153 T14261 c:collection1 C2479 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2423159 T14268 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11abf95[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1)))}
[junit4] 2> 2423161 T14261 c:collection1 C2479 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 53
[junit4] 2> 2423181 T14249 c:collection1 C2478 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={q=a_t:one&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=1 status=0 QTime=15
[junit4] 2> 2423283 T14261 c:collection1 C2479 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:51120/l_/collection1&rid=127.0.0.1-collection1-1429959584530-35&rows=10&version=2&q=a_t:one&requestPurpose=GET_TOP_IDS&NOW=1429959584529&isShard=true&wt=javabin&debugQuery=false} hits=1 status=0 QTime=1
[junit4] 2> 2423311 T14266 c:collection1 C2479 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=https://127.0.0.1:51120/l_/collection1&rid=127.0.0.1-collection1-1429959584530-35&version=2&q=a_t:one&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1429959584529&ids=0&isShard=true&wt=javabin&debugQuery=true} status=0 QTime=2
[junit4] 2> 2423319 T14264 c:collection1 C2479 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={q=a_t:one&shards=https://127.0.0.1:51120/l_/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true} rid=127.0.0.1-collection1-1429959584530-35 hits=1 status=0 QTime=132
[junit4] 2> 2423332 T14249 c:collection1 C2478 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={wt=javabin&version=2} {add=[1 (1499421301454929920)]} 0 5
[junit4] 2> 2423343 T14265 c:collection1 C2479 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={wt=javabin&version=2} {add=[1 (1499421301465415680)]} 0 5
[junit4] 2> 2423348 T14251 c:collection1 C2478 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2423360 T14251 c:collection1 C2478 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@21e91f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32ca53),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@21e91f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32ca53),segFN=segments_3,generation=3}
[junit4] 2> 2423361 T14251 c:collection1 C2478 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2423362 T14251 c:collection1 C2478 oass.SolrIndexSearcher.<init> Opening Searcher@63fd16[collection1] main
[junit4] 2> 2423363 T14251 c:collection1 C2478 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2423372 T14256 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@63fd16[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1) Uninverting(_1(5.2.0):c1)))}
[junit4] 2> 2423374 T14251 c:collection1 C2478 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 27
[junit4] 2> 2423380 T14262 c:collection1 C2479 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2423398 T14262 c:collection1 C2479 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@404420 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13ca96e),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@404420 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13ca96e),segFN=segments_3,generation=3}
[junit4] 2> 2423399 T14262 c:collection1 C2479 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2423400 T14262 c:collection1 C2479 oass.SolrIndexSearcher.<init> Opening Searcher@e1d092[collection1] main
[junit4] 2> 2423403 T14262 c:collection1 C2479 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2423407 T14268 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e1d092[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1) Uninverting(_1(5.2.0):c1)))}
[junit4] 2> 2423420 T14262 c:collection1 C2479 oasup.LogUpdateProcessor.finish [collection1] webapp=/l_ path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 41
[junit4] 2> 2423435 T14254 c:collection1 C2478 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={q=a_t:one+a_t:four&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=2 status=0 QTime=5
[junit4] 2> 2423453 T14266 c:collection1 C2479 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:51120/l_/collection1&rid=127.0.0.1-collection1-1429959584788-36&rows=10&version=2&q=a_t:one+a_t:four&requestPurpose=GET_TOP_IDS&NOW=1429959584787&isShard=true&wt=javabin&debugQuery=false} hits=2 status=0 QTime=2
[junit4] 2> 2423468 T14264 c:collection1 C2479 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=https://127.0.0.1:51120/l_/collection1&rid=127.0.0.1-collection1-1429959584788-36&version=2&q=a_t:one+a_t:four&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1429959584787&ids=0,1&isShard=true&wt=javabin&debugQuery=true} status=0 QTime=3
[junit4] 2> 2423485 T14261 c:collection1 C2479 oasc.SolrCore.execute [collection1] webapp=/l_ path=/select params={q=a_t:one+a_t:four&shards=https://127.0.0.1:51120/l_/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true} rid=127.0.0.1-collection1-1429959584788-36 hits=2 status=0 QTime=40
[junit4] 2> 2423487 T14245 oas.SolrTestCaseJ4.tearDown ###Ending test
[junit4] 2> 2423502 T14245 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20145653
[junit4] 2> 2423505 T14245 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@13b8ef0
[junit4] 2> 2423506 T14245 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=277,transaction_logs_total_number=2}
[junit4] 2> 2423507 T14245 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2423507 T14245 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2423508 T14245 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2423510 T14245 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2423548 T14245 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2423549 T14245 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\;done=false>>]
[junit4] 2> 2423550 T14245 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 2423551 T14245 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index;done=false>>]
[junit4] 2> 2423566 T14245 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index
[junit4] 2> 2423567 T14245 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/l_,null}
[junit4] 2> 2423753 T14245 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=18895036
[junit4] 2> 2423755 T14245 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e43d0
[junit4] 2> 2423756 T14245 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=277,transaction_logs_total_number=2}
[junit4] 2> 2423756 T14245 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2423756 T14245 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2423757 T14245 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2423761 T14245 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2423870 T14245 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2423870 T14245 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\;done=false>>]
[junit4] 2> 2423871 T14245 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 2423871 T14245 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index;done=false>>]
[junit4] 2> 2423871 T14245 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index
[junit4] 2> 2423873 T14245 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/l_,null}
[junit4] 2> 2424422 T14245 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2424449 T14245 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2424475 T14245 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:51126
[junit4] 2> 2424476 T14245 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/l_, hostPort=51126, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores}
[junit4] 2> 2424485 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2424487 T14245 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\'
[junit4] 2> 2424551 T14245 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\solr.xml
[junit4] 2> 2424563 T14245 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores
[junit4] 2> 2424565 T14245 oasc.CoreContainer.<init> New CoreContainer 20529359
[junit4] 2> 2424565 T14245 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\]
[junit4] 2> 2424566 T14245 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\lib
[junit4] 2> 2424566 T14245 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\lib).
[junit4] 2> 2424585 T14245 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2424593 T14245 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2424631 T14245 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2424632 T14245 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2424632 T14245 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2424637 T14245 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores
[junit4] 2> 2424639 T14245 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\, configSet=collection1, dataDir=data\}
[junit4] 2> 2424640 T14245 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\
[junit4] 2> 2424641 T14245 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2424667 T14280 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2424668 T14280 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2424669 T14280 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2424734 T14280 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2424753 T14280 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 2424795 T14280 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2424796 T14280 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 2424810 T14280 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2425119 T14280 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2425136 T14280 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2425138 T14280 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2425155 T14280 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2425159 T14280 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2425163 T14280 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2425164 T14280 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2425165 T14280 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2425165 T14280 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2425166 T14280 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2425166 T14280 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2425166 T14280 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2425167 T14280 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2425168 T14280 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
[junit4] 2> 2425168 T14280 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a831
[junit4] 2> 2425169 T14280 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 2425170 T14280 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index/
[junit4] 2> 2425171 T14280 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2425172 T14280 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\control\cores\collection1\data\index
[junit4] 2> 2425173 T14280 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.9765625, floorSegmentMB=1.482421875, forceMergeDeletesPctAllowed=4.42548809326272, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2425186 T14280 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@b82484 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c2262c),segFN=segments_1,generation=1}
[junit4] 2> 2425188 T14280 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2425199 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2425200 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2425200 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2425201 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2425201 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2425201 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2425202 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2425202 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2425202 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2425202 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2425203 T14280 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2425203 T14280 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2425240 T14280 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2425243 T14280 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2425247 T14280 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2425250 T14280 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2425260 T14280 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2425265 T14280 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 2425265 T14280 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 2425267 T14280 c:collection1 oasc.SolrCore.initStatsCache Using statsCache impl: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2425270 T14280 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2425270 T14280 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 2425271 T14280 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2425271 T14280 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2425272 T14280 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7766536415391114]
[junit4] 2> 2425273 T14280 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@b82484 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c2262c),segFN=segments_1,generation=1}
[junit4] 2> 2425274 T14280 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2425274 T14280 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@126557a[collection1] main
[junit4] 2> 2425275 T14280 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 2425275 T14280 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 2425275 T14280 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2425275 T14280 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 2425281 T14280 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2425302 T14280 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2425303 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 2425304 T14280 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2425305 T14245 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2425306 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2425305 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 2425354 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 2425355 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 2425356 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2425356 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 2425357 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 2425358 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 2425360 T14281 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 2425363 T14281 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@126557a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2425598 T14245 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2425606 T14245 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2425609 T14245 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:51129
[junit4] 2> 2425610 T14245 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/l_, hostPort=51129, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores}
[junit4] 2> 2425638 T14245 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2425639 T14245 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\'
[junit4] 2> 2425684 T14245 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\solr.xml
[junit4] 2> 2425694 T14245 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores
[junit4] 2> 2425696 T14245 oasc.CoreContainer.<init> New CoreContainer 20152430
[junit4] 2> 2425696 T14245 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\]
[junit4] 2> 2425696 T14245 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\lib
[junit4] 2> 2425697 T14245 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\lib).
[junit4] 2> 2425709 T14245 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2425714 T14245 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2425718 T14245 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2425719 T14245 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2425719 T14245 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2425724 T14245 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores
[junit4] 2> 2425726 T14245 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\, configSet=collection1, dataDir=data\}
[junit4] 2> 2425726 T14245 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\
[junit4] 2> 2425727 T14245 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2425728 T14292 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2425730 T14292 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2425730 T14292 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2425783 T14292 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2425800 T14292 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 2425832 T14292 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2425832 T14292 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 2425839 T14292 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2426053 T14292 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2426067 T14292 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2426071 T14292 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2426084 T14292 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2426088 T14292 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2426093 T14292 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2426093 T14292 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2426095 T14292 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2426096 T14292 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2426097 T14292 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2426097 T14292 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2426100 T14292 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2426100 T14292 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2426101 T14292 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
[junit4] 2> 2426101 T14292 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a831
[junit4] 2> 2426103 T14292 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 2426103 T14292 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index/
[junit4] 2> 2426104 T14292 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2426105 T14292 c:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001\tempDir-001\shard0\cores\collection1\data\index
[junit4] 2> 2426108 T14292 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.9765625, floorSegmentMB=1.482421875, forceMergeDeletesPctAllowed=4.42548809326272, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2426109 T14292 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8db9f5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@483b28),segFN=segments_1,generation=1}
[junit4] 2> 2426110 T14292 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2426160 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2426161 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2426162 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2426162 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2426162 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2426163 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2426163 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2426163 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2426163 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2426164 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2426164 T14292 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2426164 T14292 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2426171 T14292 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2426175 T14292 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=6
[...truncated too long message...]
t4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestDefaultStatsCache 25670BDEF88252D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {range_facet_l_dv=Lucene50(blocksize=128), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=FSTOrd50, a_t=FSTOrd50, shard_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=Lucene50(blocksize=128), range_facet_i_dv=FSTOrd50, text=PostingsFormat(name=Asserting), range_facet_l=FSTOrd50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=uk_UA, timezone=America/Belize
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40 (32-bit)/cpus=3,threads=1,free=137320688,total=382730240
[junit4] 2> NOTE: All tests run in this JVM: [TestFunctionQuery, OverriddenZkACLAndCredentialsProvidersTest, TestXIncludeConfig, TestManagedSchemaFieldResource, TestSortingResponseWriter, ReplicationFactorTest, TestSolrDynamicMBean, TestSchemaResource, TestDynamicFieldResource, TestStressLucene, VMParamsZkACLAndCredentialsProvidersTest, TestCoreContainer, AlternateDirectoryTest, XmlUpdateRequestHandlerTest, TestAddFieldRealTimeGet, JSONWriterTest, TestIndexSearcher, TestMacros, TestHashPartitioner, TestUniqueKeyFieldResource, StressHdfsTest, TestSurroundQueryParser, TestTrackingShardHandlerFactory, TestFastLRUCache, ShardRoutingTest, TestChildDocTransformer, BadComponentTest, AliasIntegrationTest, CoreAdminRequestStatusTest, TestManagedSynonymFilterFactory, TestNRTOpen, CoreMergeIndexesAdminHandlerTest, AssignTest, TestFaceting, SimpleMLTQParserTest, TestComplexPhraseQParserPlugin, AddSchemaFieldsUpdateProcessorFactoryTest, TestTolerantSearch, SuggesterWFSTTest, CollectionReloadTest, TestLRUStatsCache, TestHighFrequencyDictionaryFactory, FieldAnalysisRequestHandlerTest, TestLazyCores, TestWordDelimiterFilterFactory, TestFileDictionaryLookup, TestFieldSortValues, TestStressRecovery, FacetPivotSmallTest, SuggesterTest, TestUpdate, ChaosMonkeyNothingIsSafeTest, TestQueryUtils, TestAtomicUpdateErrorCases, ZkControllerTest, DocValuesMissingTest, TestRecoveryHdfs, ClusterStateTest, HdfsThreadLeakTest, DefaultValueUpdateProcessorTest, DistributedFacetPivotWhiteBoxTest, RequestLoggingTest, AnalyticsQueryTest, ClusterStateUpdateTest, TestSchemaVersionResource, MultiTermTest, TestSolrIndexConfig, DistanceUnitsTest, DistribCursorPagingTest, OverseerRolesTest, TestHashQParserPlugin, TestManagedSchema, SharedFSAutoReplicaFailoverUtilsTest, SolrInfoMBeanTest, TestConfigOverlay, MultiThreadedOCPTest, TestSolrQueryParserResource, TestWriterPerf, TestFieldCollectionResource, ResponseLogComponentTest, SolrIndexSplitterTest, TestReplicaProperties, TestCharFilters, BasicDistributedZkTest, TestAnalyzedSuggestions, TestShortCircuitedRequests, TestJmxIntegration, TestStandardQParsers, ShowFileRequestHandlerTest, TestPerFieldSimilarity, TestDocumentBuilder, HardAutoCommitTest, PeerSyncTest, SampleTest, RequiredFieldsTest, TestExactSharedStatsCache, TestCollationFieldDocValues, TestQueryTypes, TestLRUCache, PingRequestHandlerTest, TestNoOpRegenerator, RollingRestartTest, TestHighlightDedupGrouping, DistributedQueryComponentOptimizationTest, TestFieldResource, DirectUpdateHandlerTest, DirectSolrConnectionTest, TestGroupingSearch, AtomicUpdatesTest, TestIndexingPerformance, HdfsBasicDistributedZkTest, TestFreeTextSuggestions, TestCollationField, TestBinaryResponseWriter, TestSort, SolrTestCaseJ4Test, NoCacheHeaderTest, SolrCoreTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, TestRandomFaceting, LeaderElectionTest, TestRandomDVFaceting, ZkCLITest, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestStressReorder, TestStressVersions, DistributedTermsComponentTest, TestRangeQuery, SimpleFacetsTest, StatsComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, ConvertedLegacyTest, BasicFunctionalityTest, SoftAutoCommitTest, DistributedQueryElevationComponentTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, TestTrie, TestCSVLoader, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, QueryEqualityTest, DistanceFunctionTest, IndexBasedSpellCheckerTest, DirectSolrSpellCheckerTest, PrimitiveFieldTypeTest, TestOmitPositions, RequestHandlersTest, TestValueSourceCache, TestArbitraryIndexDir, SolrPluginUtilsTest, UpdateRequestProcessorFactoryTest, UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter, TestComponentsName, TestLFUCache, TestMergePolicyConfig, TestDocSet, TestSearchPerf, TestElisionMultitermQuery, ExternalFileFieldSortTest, TestPhraseSuggestions, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, PluginInfoTest, DateMathParserTest, PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, TestSolrJ, TestUtils, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, CircularListTest, TestRTGBase, TestDistributedMissingSort, ConcurrentDeleteAndCreateCollectionTest, ConnectionManagerTest, CustomCollectionTest, DistribJoinFromCollectionTest, HttpPartitionTest, RecoveryAfterSoftCommitTest, SSLMigrationTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCollectionAPI, TestCryptoKeys, TriLevelCompositeIdRoutingTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, TestConfigSets, TestDynamicLoading, TestReplicationHandlerBackup, TestRestoreCore, TestSolrConfigHandlerCloud, DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotSmallTest, DistributedSuggestComponentTest, TestExpandComponent, TestPivotHelperCode, HighlighterMaxOffsetTest, TestManagedResourceStorage, TestRestManager, TestBulkSchemaAPI, TestClassNameShortening, TestFieldTypeCollectionResource, TestFieldTypeResource, TestManagedSchemaDynamicFieldResource, ChangedSchemaMergeTest, DateRangeFieldTest, TestCloudManagedSchema, TestSchemaManager, AnalyticsMergeStrategyTest, CursorMarkTest, MergeStrategyTest, TestCollapseQParserPlugin, TestCustomSort, TestInitQParser, TestMaxScoreQueryParser, TestOverriddenPrefixQueryForCustomFieldType, TestSimpleQParserPlugin, TestSolr4Spatial2, TestOrdValues, BJQParserTest, TestJsonRequest, TestNonDefinedSimilarityFactory, TestDefaultStatsCache]
[junit4] Completed [484/484] on J0 in 96.26s, 1 test, 1 error <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:536: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:484: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:229: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:511: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1434: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:991: There were test failures: 484 suites, 1946 tests, 1 error, 63 ignored (34 assumptions)
Total time: 60 minutes 29 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
ERROR: Failed to archive artifacts: **/*.events,heapdumps/**,**/hs_err_pid*
java.io.IOException: Failed to extract C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows/transfer of 2 files
at hudson.FilePath.readFromTar(FilePath.java:2299)
at hudson.FilePath.copyRecursiveTo(FilePath.java:2208)
at jenkins.model.StandardArtifactManager.archive(StandardArtifactManager.java:61)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:219)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:74)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:761)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:721)
at hudson.model.Build$BuildExecution.post2(Build.java:183)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:670)
at hudson.model.Run.execute(Run.java:1766)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:98)
at hudson.model.Executor.run(Executor.java:374)
Caused by: java.io.IOException: Truncated TAR archive
at org.apache.commons.compress.archivers.tar.TarArchiveInputStream.read(TarArchiveInputStream.java:614)
at java.io.InputStream.read(InputStream.java:101)
at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1792)
at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1769)
at org.apache.commons.io.IOUtils.copy(IOUtils.java:1744)
at hudson.util.IOUtils.copy(IOUtils.java:40)
at hudson.FilePath.readFromTar(FilePath.java:2289)
... 13 more
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any