lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 421 - Still Failing!
Date Fri, 26 Apr 2013 10:02:31 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/421/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC

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

Error Message:
ERROR: SolrIndexSearcher opens=36 closes=35

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=36 closes=35
	at __randomizedtesting.SeedInfo.seed([60B732CE43CAA39B]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:252)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:101)
	at sun.reflect.GeneratedMethodAccessor24.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


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=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:722)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 
   1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:722)
	at __randomizedtesting.SeedInfo.seed([60B732CE43CAA39B]: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=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:722)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:722)
	at __randomizedtesting.SeedInfo.seed([60B732CE43CAA39B]:0)




Build Log:
[...truncated 8996 lines...]
[junit4:junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.267; org.apache.solr.SolrTestCaseJ4; ####initCore
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.268; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.270; org.apache.solr.core.SolrResourceLoader; Adding 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.271; org.apache.solr.core.SolrResourceLoader; Adding 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.445; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.586; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.594; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.606; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.680; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.685; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.686; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.687; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.734; org.apache.solr.core.CoreContainer; New CoreContainer 564693755
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.863; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.864; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.866; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.867; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.869; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.870; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:07.870; org.apache.solr.core.SolrCore; [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.872; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.878; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7dc8da70 lockFactory=org.apache.lucene.store.NativeFSLockFactory@171f8bc8),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.879; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:07.991; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.053; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.055; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.056; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.076; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.089; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@4a92c13f main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.090; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@4a92c13f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.091; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.092; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.105; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.111; org.apache.solr.SolrTestCaseJ4; ####initCore end
[junit4:junit4]   2> Creating dataDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.124; org.apache.solr.SolrTestCaseJ4; ###Starting testLazySearch
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.239; org.apache.solr.core.CoreContainer; New CoreContainer 1711767701
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.241; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.243; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.521; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.523; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.524; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.525; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.526; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.527; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.528; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.529; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.530; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.531; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.580; org.apache.solr.core.SolrCores; Allocating transient cache for 4 transient cores
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.592; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.619; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.628; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy2' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.630; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.631; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.647; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy5' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.658; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.853; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.912; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:08.925; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.338; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.340; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.346; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.360; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.362; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.375; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.384; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.396; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.372; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.550; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.553; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.554; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.554; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.555; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.564; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.568; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.585; org.apache.solr.core.SolrCore; [collectionLazy5] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.586; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.586; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.584; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.584; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.592; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.593; org.apache.solr.core.SolrCore; [collectionLazy2] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.594; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.595; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.597; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.596; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:09.606; org.apache.solr.core.SolrCore; [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.605; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:09.607; org.apache.solr.core.SolrCore; [collectionLazy5] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.614; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.605; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.627; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:09.628; org.apache.solr.core.SolrCore; [collectionLazy2] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.629; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.608; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.644; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4fa12bef lockFactory=org.apache.lucene.store.NativeFSLockFactory@d26f67f),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.648; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.654; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.660; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.646; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@26a661d5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@793b48fd),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.662; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.662; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.663; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@325d5dfd lockFactory=org.apache.lucene.store.NativeFSLockFactory@2233f372),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.664; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.666; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.667; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.669; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.670; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.672; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.672; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.674; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.664; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.676; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.698; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.710; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.716; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.719; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@26e24671 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.721; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@26e24671 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.722; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.722; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.727; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@7627621e main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.729; org.apache.solr.core.SolrCore; [collectionLazy2] Registered new searcher Searcher@7627621e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.735; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.738; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.743; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@6ef7fb96 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.747; org.apache.solr.core.SolrCore; [collectionLazy5] Registered new searcher Searcher@6ef7fb96 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.747; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.748; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.752; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.754; org.apache.solr.core.SolrCores; Opening transient core collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.758; org.apache.solr.core.CoreContainer; registering core: collectionLazy5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.760; org.apache.solr.core.CoreContainer; registering core: collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.763; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy4' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.764; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:09.956; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.099; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.100; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.105; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.183; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.188; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.189; org.apache.solr.core.SolrCore; [collectionLazy4] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.190; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.192; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.203; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.204; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:10.205; org.apache.solr.core.SolrCore; [collectionLazy4] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.208; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.218; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3d9811fa lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a3ba4fc),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.219; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.222; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.230; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.232; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.234; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.279; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.293; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@5fd02924 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.296; org.apache.solr.core.SolrCore; [collectionLazy4] Registered new searcher Searcher@5fd02924 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.297; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.297; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.305; org.apache.solr.core.CoreContainer; registering core: collectionLazy4
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.309; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3d9811fa lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a3ba4fc),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.310; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.314; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.318; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3d9811fa lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a3ba4fc),segFN=segments_1,generation=1}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3d9811fa lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a3ba4fc),segFN=segments_2,generation=2}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.320; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.326; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@45ed32ee main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.330; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.332; org.apache.solr.core.SolrCore; [collectionLazy4] Registered new searcher Searcher@45ed32ee main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C7)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.339; org.apache.solr.core.SolrCore; [collection1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.366; org.apache.solr.core.SolrCore; [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.369; org.apache.solr.core.SolrCore; [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=0 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.372; org.apache.solr.core.SolrCore; [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.383; org.apache.solr.core.SolrCore; [collection1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.386; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=1711767701
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.387; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@702391c5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.393; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:10.394; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.395; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.395; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.396; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.400; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.402; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.403; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.403; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.406; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.411; org.apache.solr.core.SolrCore; [collectionLazy5]  CLOSING SolrCore org.apache.solr.core.SolrCore@62c41cca
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.419; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:10.425; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.426; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.426; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.427; org.apache.solr.core.SolrCore; [collectionLazy5] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.428; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.429; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.429; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.430; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.431; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.431; org.apache.solr.core.SolrCore; [collectionLazy4]  CLOSING SolrCore org.apache.solr.core.SolrCore@42624824
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:10.438; org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean; Could not getStatistics on info bean org.apache.solr.search.SolrIndexSearcher
[junit4:junit4]   1> org.apache.lucene.store.AlreadyClosedException: this IndexReader is closed
[junit4:junit4]   1> 	at org.apache.lucene.index.IndexReader.ensureOpen(IndexReader.java:250)
[junit4:junit4]   1> 	at org.apache.lucene.index.StandardDirectoryReader.getVersion(StandardDirectoryReader.java:316)
[junit4:junit4]   1> 	at org.apache.solr.search.SolrIndexSearcher.getStatistics(SolrIndexSearcher.java:2106)
[junit4:junit4]   1> 	at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:232)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getAttribute(DefaultMBeanServerInterceptor.java:644)
[junit4:junit4]   1> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttribute(JmxMBeanServer.java:669)
[junit4:junit4]   1> 	at org.apache.solr.core.JmxMonitoredMap.unregister(JmxMonitoredMap.java:174)
[junit4:junit4]   1> 	at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:984)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCores.close(SolrCores.java:125)
[junit4:junit4]   1> 	at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:610)
[junit4:junit4]   1> 	at org.apache.solr.core.TestLazyCores.testLazySearch(TestLazyCores.java:182)
[junit4:junit4]   1> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   1> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]   1> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   1> 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   1> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   1> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:10.442; org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean; Could not getStatistics on info bean org.apache.solr.search.SolrIndexSearcher
[junit4:junit4]   1> org.apache.lucene.store.AlreadyClosedException: this IndexReader is closed
[junit4:junit4]   1> 	at org.apache.lucene.index.IndexReader.ensureOpen(IndexReader.java:250)
[junit4:junit4]   1> 	at org.apache.lucene.index.StandardDirectoryReader.getVersion(StandardDirectoryReader.java:316)
[junit4:junit4]   1> 	at org.apache.solr.search.SolrIndexSearcher.getStatistics(SolrIndexSearcher.java:2106)
[junit4:junit4]   1> 	at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:232)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
[junit4:junit4]   1> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4:junit4]   1> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:537)
[junit4:junit4]   1> 	at org.apache.solr.core.JmxMonitoredMap.unregister(JmxMonitoredMap.java:175)
[junit4:junit4]   1> 	at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:984)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCores.close(SolrCores.java:125)
[junit4:junit4]   1> 	at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:610)
[junit4:junit4]   1> 	at org.apache.solr.core.TestLazyCores.testLazySearch(TestLazyCores.java:182)
[junit4:junit4]   1> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   1> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]   1> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   1> 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   1> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   1> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   1> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   1> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.460; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=7,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.461; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.461; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.462; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.469; org.apache.solr.core.SolrCore; [collectionLazy4] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.472; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.473; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.474; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.475; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.476; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.477; org.apache.solr.core.SolrCore; [collectionLazy2]  CLOSING SolrCore org.apache.solr.core.SolrCore@26feaf1e
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.486; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:10.487; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.488; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.488; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.489; org.apache.solr.core.SolrCore; [collectionLazy2] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.490; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.491; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.492; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.493; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.493; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.572; org.apache.solr.SolrTestCaseJ4; ###Ending testLazySearch
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.586; org.apache.solr.SolrTestCaseJ4; ###Starting testPersistence
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.682; org.apache.solr.core.CoreContainer; New CoreContainer 1545787853
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.683; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.683; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.935; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.936; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.939; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.942; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.946; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.947; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.948; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.949; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.950; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:10.952; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.014; org.apache.solr.core.SolrCores; Allocating transient cache for 4 transient cores
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.015; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.039; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.041; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.050; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy2' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.052; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.051; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy5' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.056; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.371; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.409; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.424; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.710; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.712; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.813; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.868; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.869; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.878; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.896; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.897; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.907; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.928; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.941; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.941; org.apache.solr.core.SolrCore; [collectionLazy5] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.941; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.942; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.950; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.950; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:11.951; org.apache.solr.core.SolrCore; [collectionLazy5] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.951; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.956; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b79f53d lockFactory=org.apache.lucene.store.NativeFSLockFactory@626ccc7e),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.957; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.958; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.962; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.963; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:11.964; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.003; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.005; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.006; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.006; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.010; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.011; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.013; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.025; org.apache.solr.core.SolrCore; [collectionLazy2] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.026; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.032; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.034; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:12.035; org.apache.solr.core.SolrCore; [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.037; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.033; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.041; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.042; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:12.043; org.apache.solr.core.SolrCore; [collectionLazy2] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.043; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.060; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.067; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5139df1b lockFactory=org.apache.lucene.store.NativeFSLockFactory@15a137a4),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.068; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.070; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.073; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.074; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.075; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.078; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@54ef7e84 lockFactory=org.apache.lucene.store.NativeFSLockFactory@188c6de0),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.079; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.097; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.105; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@329203a8 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.107; org.apache.solr.core.SolrCore; [collectionLazy5] Registered new searcher Searcher@329203a8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.108; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.109; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.121; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.122; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.124; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.145; org.apache.solr.core.CoreContainer; registering core: collectionLazy5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.146; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.161; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@8638f54 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.164; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@8638f54 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.164; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.165; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.176; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.189; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@7702d62e main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.191; org.apache.solr.core.SolrCore; [collectionLazy2] Registered new searcher Searcher@7702d62e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.192; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.192; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.198; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.200; org.apache.solr.core.SolrCores; Opening transient core collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.201; org.apache.solr.core.CoreContainer; registering core: collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.234; org.apache.solr.core.CoreContainer; Creating SolrCore 'core1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.236; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.396; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.538; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.544; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.562; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.634; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.639; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.642; org.apache.solr.core.SolrCore; [core1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.643; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.644; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.647; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.648; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:12.648; org.apache.solr.core.SolrCore; [core1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.650; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.670; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@71b0d7c2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@173019f),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.672; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.674; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.678; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.679; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.680; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.717; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.738; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@65621c3 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.741; org.apache.solr.core.SolrCore; [core1] Registered new searcher Searcher@65621c3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.742; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.743; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.759; org.apache.solr.core.CoreContainer; Creating SolrCore 'core2' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:12.761; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.027; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.268; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.271; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.282; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.362; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.367; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.369; org.apache.solr.core.SolrCore; [core2] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.370; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.375; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.376; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.377; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:13.377; org.apache.solr.core.SolrCore; [core2] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.379; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.386; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@179d6b3c lockFactory=org.apache.lucene.store.NativeFSLockFactory@238293c),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.388; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.391; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.396; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.397; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.398; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.428; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.444; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@68a84820 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.447; org.apache.solr.core.SolrCore; [core2] Registered new searcher Searcher@68a84820 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.448; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.449; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.460; org.apache.solr.core.CoreContainer; Creating SolrCore 'core3' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.462; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.668; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.817; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.820; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.830; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.919; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.923; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.924; org.apache.solr.core.SolrCore; [core3] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.925; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.926; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.928; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.928; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:13.929; org.apache.solr.core.SolrCore; [core3] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.930; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.941; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37b02d20 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e9bcb0b),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.942; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.944; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.950; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.952; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.953; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:13.978; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.006; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@51b43585 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.008; org.apache.solr.core.SolrCore; [core3] Registered new searcher Searcher@51b43585 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.009; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.009; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.020; org.apache.solr.core.CoreContainer; Creating SolrCore 'core4' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.021; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.175; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.323; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.326; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.337; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.402; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.408; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.410; org.apache.solr.core.SolrCore; [core4] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.411; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.412; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.422; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.423; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:14.424; org.apache.solr.core.SolrCore; [core4] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.426; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.434; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@66958595 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1584afc9),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.435; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.438; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.445; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.446; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.447; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.481; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.506; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@63ebe38e main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.509; org.apache.solr.core.SolrCore; [core4] Registered new searcher Searcher@63ebe38e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.510; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.511; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.521; org.apache.solr.core.CoreContainer; Persisting cores config to ./org.apache.solr.core.TestLazyCores_testlazy/lazy1.solr.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.559; org.apache.solr.core.SolrXMLSerializer; Persisting cores config to ./org.apache.solr.core.TestLazyCores_testlazy/lazy1.solr.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.686; org.apache.solr.core.SolrCore; [collectionLazy2]  CLOSING SolrCore org.apache.solr.core.SolrCore@7b8d3e36
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.699; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.700; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.700; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.702; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.714; org.apache.solr.core.SolrCore; [collectionLazy2] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.716; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.717; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.717; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.718; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.719; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.720; org.apache.solr.core.SolrCore; [collectionLazy5]  CLOSING SolrCore org.apache.solr.core.SolrCore@2b08f716
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.728; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.729; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.729; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.730; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.731; org.apache.solr.core.SolrCore; [collectionLazy5] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.733; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.734; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.735; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.735; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.736; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.737; org.apache.solr.core.SolrCore; [core1]  CLOSING SolrCore org.apache.solr.core.SolrCore@77e559a7
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.744; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.745; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.747; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.764; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.766; org.apache.solr.core.SolrCore; [core1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.767; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.767; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.768; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.768; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.769; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.769; org.apache.solr.core.SolrCore; [core2]  CLOSING SolrCore org.apache.solr.core.SolrCore@77328e29
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.779; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.780; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.781; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.781; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.783; org.apache.solr.core.SolrCore; [core2] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.784; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.784; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.785; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.785; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.786; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.786; org.apache.solr.core.SolrCore; [core3]  CLOSING SolrCore org.apache.solr.core.SolrCore@43b7e5e7
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.797; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.802; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.803; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.804; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.805; org.apache.solr.core.SolrCore; [core3] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.806; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.807; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.807; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.808; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.809; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core3/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.811; org.apache.solr.core.SolrCore; [core4]  CLOSING SolrCore org.apache.solr.core.SolrCore@11e3cfa5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.823; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.824; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.824; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.825; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.826; org.apache.solr.core.SolrCore; [core4] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.847; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.849; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.850; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.852; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.855; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/./core4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.857; org.apache.solr.core.CoreContainer; Persisting cores config to ./org.apache.solr.core.TestLazyCores_testlazy/lazy2.solr.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.874; org.apache.solr.core.SolrXMLSerializer; Persisting cores config to ./org.apache.solr.core.TestLazyCores_testlazy/lazy2.solr.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.895; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=1545787853
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.896; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7eaa8abd
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.905; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:14.906; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.907; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.907; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.908; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.910; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.910; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.911; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.911; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:14.912; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.027; org.apache.solr.SolrTestCaseJ4; ###Ending testPersistence
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.038; org.apache.solr.SolrTestCaseJ4; ###Starting testCreateSame
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.139; org.apache.solr.core.CoreContainer; New CoreContainer 563870972
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.140; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.140; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.461; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.463; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.464; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.465; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.466; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.467; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.468; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.469; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.470; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.472; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.528; org.apache.solr.core.SolrCores; Allocating transient cache for 4 transient cores
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.530; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.547; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.549; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.602; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy2' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.617; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.603; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy5' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.634; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.738; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.816; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.871; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.974; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.975; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:15.982; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.110; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.111; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.123; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.126; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.147; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.150; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.155; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.161; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.162; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.163; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.186; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.197; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.197; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:16.198; org.apache.solr.core.SolrCore; [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.199; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.233; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@183da42c lockFactory=org.apache.lucene.store.NativeFSLockFactory@6452bd49),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.235; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.237; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.242; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.245; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.246; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.281; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.284; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.285; org.apache.solr.core.SolrCore; [collectionLazy5] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.286; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.286; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.292; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.298; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.298; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:16.299; org.apache.solr.core.SolrCore; [collectionLazy5] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.311; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.314; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.314; org.apache.solr.core.SolrCore; [collectionLazy2] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.315; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.316; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.311; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.317; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@7709068f main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.319; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@7709068f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.320; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.321; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.332; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.333; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.339; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:16.340; org.apache.solr.core.SolrCore; [collectionLazy2] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.340; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6ec59470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@14f93654),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.348; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.349; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.355; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.357; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@607a1bf4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@169ba7a7),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.358; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.360; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.360; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.362; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.363; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.364; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.366; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.367; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.388; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.392; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.404; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@7d7e1d80 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.406; org.apache.solr.core.SolrCore; [collectionLazy5] Registered new searcher Searcher@7d7e1d80 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.407; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.407; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.415; org.apache.solr.core.CoreContainer; registering core: collectionLazy5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.421; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@1ed2d23f main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.423; org.apache.solr.core.SolrCore; [collectionLazy2] Registered new searcher Searcher@1ed2d23f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.423; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.424; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.430; org.apache.solr.core.SolrCores; Opening transient core collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.431; org.apache.solr.core.CoreContainer; registering core: collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.434; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy4' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.436; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.662; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.766; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.768; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.788; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.840; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.848; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.849; org.apache.solr.core.SolrCore; [collectionLazy4] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.850; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.851; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.852; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.852; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:16.853; org.apache.solr.core.SolrCore; [collectionLazy4] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.854; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.860; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6d8e4f7e lockFactory=org.apache.lucene.store.NativeFSLockFactory@60e3fad3),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.861; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.864; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.867; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.869; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.871; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.893; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.905; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@3a462d19 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.907; org.apache.solr.core.SolrCore; [collectionLazy4] Registered new searcher Searcher@3a462d19 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.908; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.909; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.924; org.apache.solr.core.CoreContainer; registering core: collectionLazy4
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.926; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy6' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:16.927; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.076; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.236; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.239; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.246; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.318; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.323; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.325; org.apache.solr.core.SolrCore; [collectionLazy6] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.327; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.328; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.330; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.331; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:17.335; org.apache.solr.core.SolrCore; [collectionLazy6] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.337; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.347; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@41defa7a lockFactory=org.apache.lucene.store.NativeFSLockFactory@25480b03),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.348; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.351; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.358; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.360; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.362; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.387; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.409; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@7f7f2e13 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.411; org.apache.solr.core.SolrCore; [collectionLazy6] Registered new searcher Searcher@7f7f2e13 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.413; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.414; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.426; org.apache.solr.core.SolrCores; Opening transient core collectionLazy6
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.427; org.apache.solr.core.CoreContainer; registering core: collectionLazy6
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:17.482; org.apache.solr.handler.admin.CoreAdminHandler; Creating a core with existing name is not allowed
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:17.483; org.apache.solr.handler.admin.CoreAdminHandler; Creating a core with existing name is not allowed
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:17.485; org.apache.solr.handler.admin.CoreAdminHandler; Creating a core with existing name is not allowed
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:17.486; org.apache.solr.handler.admin.CoreAdminHandler; Creating a core with existing name is not allowed
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.487; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=563870972
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.490; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1fb13185
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.503; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:17.504; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.505; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.505; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.506; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.508; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.508; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.509; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.509; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.510; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.511; org.apache.solr.core.SolrCore; [collectionLazy5]  CLOSING SolrCore org.apache.solr.core.SolrCore@694dfe47
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.519; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:17.521; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.522; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.523; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.524; org.apache.solr.core.SolrCore; [collectionLazy5] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.526; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.529; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.530; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.531; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.532; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.534; org.apache.solr.core.SolrCore; [collectionLazy4]  CLOSING SolrCore org.apache.solr.core.SolrCore@28ea7178
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.551; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:17.553; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.553; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.554; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.555; org.apache.solr.core.SolrCore; [collectionLazy4] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.555; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.557; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.559; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.560; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.561; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.562; org.apache.solr.core.SolrCore; [collectionLazy2]  CLOSING SolrCore org.apache.solr.core.SolrCore@61e56e2d
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.574; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:17.576; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.577; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.578; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.580; org.apache.solr.core.SolrCore; [collectionLazy2] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.582; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.585; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.588; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.592; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.594; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.594; org.apache.solr.core.SolrCore; [collectionLazy6]  CLOSING SolrCore org.apache.solr.core.SolrCore@9e84f23
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.703; org.apache.solr.update.DirectUpdateHandler2; 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:junit4]   1> INFO  - 2013-04-26 09:58:17.704; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.705; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.705; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.706; org.apache.solr.core.SolrCore; [collectionLazy6] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.709; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.710; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.711; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.711; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.712; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection6/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.816; org.apache.solr.SolrTestCaseJ4; ###Ending testCreateSame
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.830; org.apache.solr.SolrTestCaseJ4; ###Starting testLazyLoad
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.933; org.apache.solr.core.CoreContainer; New CoreContainer 729359023
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.936; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:17.937; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.191; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.191; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.192; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.193; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.193; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.194; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.194; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.195; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.195; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.196; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.232; org.apache.solr.core.SolrCores; Allocating transient cache for 4 transient cores
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.233; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.244; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.246; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.255; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy2' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.257; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.309; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy5' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.310; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.433; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.487; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.550; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.727; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.729; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.751; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.803; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.804; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.811; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.848; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.849; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.855; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.868; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.871; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.871; org.apache.solr.core.SolrCore; [collectionLazy2] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.872; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.873; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.875; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.876; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:18.876; org.apache.solr.core.SolrCore; [collectionLazy2] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.877; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection2/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.896; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.898; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.899; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.900; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.900; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.914; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.927; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:18.928; org.apache.solr.core.SolrCore; [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.930; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.932; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.933; org.apache.solr.core.SolrCore; [collectionLazy5] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.934; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.934; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.935; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.942; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.943; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:18.943; org.apache.solr.core.SolrCore; [collectionLazy5] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.944; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection5/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.949; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@116b3731 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a8ec738),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.950; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.953; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.956; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.958; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.957; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2623ce5e lockFactory=org.apache.lucene.store.NativeFSLockFactory@4504ab26),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.965; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.965; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@fd04ce lockFactory=org.apache.lucene.store.NativeFSLockFactory@5b4a94b),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.966; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.967; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.969; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.971; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.973; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.965; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.974; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.975; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.973; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:18.985; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.000; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.014; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.014; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@46048e98 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.014; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.021; org.apache.solr.core.SolrCore; [collectionLazy2] Registered new searcher Searcher@46048e98 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.022; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.023; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.028; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@408d9ac4 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.029; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@408d9ac4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.030; org.apache.solr.core.SolrCores; Opening transient core collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.030; org.apache.solr.core.CoreContainer; registering core: collectionLazy2
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.031; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@2cac0ca8 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.032; org.apache.solr.core.SolrCore; [collectionLazy5] Registered new searcher Searcher@2cac0ca8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.033; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.035; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.030; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.042; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.051; org.apache.solr.core.CoreContainer; registering core: collectionLazy5
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.053; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.057; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy3' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.058; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.197; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.297; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.298; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.304; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.352; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.356; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.357; org.apache.solr.core.SolrCore; [collectionLazy3] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.358; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.359; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.360; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.361; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:19.361; org.apache.solr.core.SolrCore; [collectionLazy3] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.362; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection3/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.368; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6365a5df lockFactory=org.apache.lucene.store.NativeFSLockFactory@a1f4bfb),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.370; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.371; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.375; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.377; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.378; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.408; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.423; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@165c9be1 main
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.425; org.apache.solr.core.SolrCore; [collectionLazy3] Registered new searcher Searcher@165c9be1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.426; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.426; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.434; org.apache.solr.core.SolrCores; Opening transient core collectionLazy3
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.434; org.apache.solr.core.CoreContainer; registering core: collectionLazy3
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.436; org.apache.solr.core.CoreContainer; Creating SolrCore 'collectionLazy4' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.437; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/'
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.579; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.704; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig-minimal.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.706; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema-tiny.xml
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.714; org.apache.solr.schema.IndexSchema; Schema name=tiny
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.764; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.767; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.768; org.apache.solr.core.SolrCore; [collectionLazy4] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.769; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3afc269b
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.770; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.771; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.771; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index/
[junit4:junit4]   1> WARN  - 2013-04-26 09:58:19.772; org.apache.solr.core.SolrCore; [collectionLazy4] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.773; org.apache.solr.core.CachingDirectoryFactory; return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.core.TestLazyCores_testlazy/collection4/data/index
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.779; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1a87f610 lockFactory=org.apache.lucene.store.NativeFSLockFactory@500e5e51),segFN=segments_1,generation=1}
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.780; org.apache.solr.core.SolrDeletionPolicy; newest commit generation = 1
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.782; org.apache.solr.core.SolrCore; created json: solr.JSONResponseWriter
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.786; org.apache.solr.core.RequestHandlers; created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.787; org.apache.solr.core.RequestHandlers; created /select: solr.SearchHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.788; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:19.821; org.apache.solr.handler.loader.XMLLoader; xs

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

/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267 [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:26.641; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:26.658; org.apache.solr.core.CachingDirectoryFactory; looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-26 09:58:26.659; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-TestLazyCores-1366970287267/index
[junit4:junit4]   1> ERROR - 2013-04-26 10:00:27.852; org.apache.solr.SolrTestCaseJ4; ERROR: SolrIndexSearcher opens=36 closes=35
[junit4:junit4]   2> 140594 T489 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4]   2> 160662 T489 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 
[junit4:junit4]   2> 	   1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4:junit4]   2> 	        at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4:junit4]   2> 	        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4:junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 160663 T489 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4:junit4]   2> 	   1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4:junit4]   2> 163676 T489 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4:junit4]   2> 	   1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4:junit4]   2> 	        at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4:junit4]   2> 	        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4:junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=174), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=174)), sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es, timezone=Europe/Monaco
[junit4:junit4]   2> NOTE: Mac OS X 10.8.3 x86_64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=2,threads=2,free=203082736,total=536870912
[junit4:junit4]   2> NOTE: All tests run in this JVM: [MoreLikeThisHandlerTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, DateMathParserTest, TestSweetSpotSimilarityFactory, TestSystemIdResolver, TestExtendedDismaxParser, TestSurroundQueryParser, TestValueSourceCache, IndexSchemaTest, TestLMJelinekMercerSimilarityFactory, TestBinaryField, SystemInfoHandlerTest, RAMDirectoryFactoryTest, DistanceFunctionTest, CopyFieldTest, TestDynamicFieldCollectionResource, TestShardHandlerFactory, IndexBasedSpellCheckerTest, TestSort, TestSolrJ, HighlighterTest, SynonymTokenizerTest, CircularListTest, ClusterStateUpdateTest, SuggesterWFSTTest, TestAnalyzedSuggestions, TestDocumentBuilder, QueryElevationComponentTest, TestPartialUpdateDeduplication, SignatureUpdateProcessorFactoryTest, SolrInfoMBeanTest, DocumentAnalysisRequestHandlerTest, XmlUpdateRequestHandlerTest, TestDFRSimilarityFactory, TestDefaultSearchFieldResource, TestSolrQueryParser, AutoCommitTest, TestComponentsName, TestFieldResource, SpellCheckCollatorTest, TestStressRecovery, TestCharFilters, BasicDistributedZk2Test, TestHashPartitioner, TestPluginEnable, TestLazyCores]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestLazyCores -Dtests.seed=60B732CE43CAA39B -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=Europe/Monaco -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.00s | TestLazyCores (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=36 closes=35
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([60B732CE43CAA39B]:0)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:252)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:101)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 
[junit4:junit4]    >    1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4:junit4]    >         at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]    >         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4:junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4:junit4]    >         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4:junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4:junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4:junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4:junit4]    >         at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([60B732CE43CAA39B]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4:junit4]    >    1) Thread[id=533, name=searcherExecutor-404-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4:junit4]    >         at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]    >         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4:junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4:junit4]    >         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4:junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4:junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4:junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4:junit4]    >         at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([60B732CE43CAA39B]:0)
[junit4:junit4] Completed in 164.07s, 6 tests, 1 failure, 2 errors <<< FAILURES!

[...truncated 744 lines...]
BUILD FAILED
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:378: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:358: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:181: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:437: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1240: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:884: There were test failures: 293 suites, 1219 tests, 3 suite-level errors, 958 ignored

Total time: 59 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message