You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/02/14 02:17:17 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0-fcs-b129) - Build
# 3701 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3701/
Java: 64bit/jdk1.8.0-fcs-b129 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
ERROR: SolrIndexSearcher opens=59 closes=58
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=59 closes=58
at __randomizedtesting.SeedInfo.seed([10CD8A71840EF02C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:335)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:139)
at sun.reflect.GeneratedMethodAccessor29.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:744)
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=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:744)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:744)
at __randomizedtesting.SeedInfo.seed([10CD8A71840EF02C]: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=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:744)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:744)
at __randomizedtesting.SeedInfo.seed([10CD8A71840EF02C]:0)
Build Log:
[...truncated 10422 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> 973849 T3632 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestLazyCores-1392340373128
[junit4] 2> 973849 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 973852 T3632 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 973852 T3632 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 973902 T3632 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 973910 T3632 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 973911 T3632 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 973914 T3632 oass.IndexSchema.readSchema [null] Schema name=tiny
[junit4] 2> 973922 T3632 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 973922 T3632 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 973922 T3632 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 973922 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 973944 T3632 oasc.CoreContainer.<init> New CoreContainer 954864054
[junit4] 2> 973944 T3632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 973946 T3632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 973950 T3632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 973951 T3632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 973951 T3632 oasc.CoreContainer.load Host Name:
[junit4] 2> 973956 T3633 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 973956 T3633 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 973956 T3633 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 973956 T3633 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 973980 T3633 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 973988 T3633 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 973988 T3633 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 973992 T3633 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 973998 T3633 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 973998 T3633 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 973999 T3633 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestLazyCores-1392340373128\
[junit4] 2> 973999 T3633 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 974000 T3633 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestLazyCores-1392340373128\
[junit4] 2> 974000 T3633 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestLazyCores-1392340373128\index/
[junit4] 2> 974000 T3633 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestLazyCores-1392340373128\index' doesn't exist. Creating new index...
[junit4] 2> 974001 T3633 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestLazyCores-1392340373128\index
[junit4] 2> 974001 T3633 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 974002 T3633 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2b8d9bda lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@286c999f),segFN=segments_1,generation=1}
[junit4] 2> 974002 T3633 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974002 T3633 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974002 T3633 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974003 T3633 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974003 T3633 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974008 T3633 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974009 T3633 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974009 T3633 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974010 T3633 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=31.3291015625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=3.492456112712458, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 974010 T3633 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2b8d9bda lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@286c999f),segFN=segments_1,generation=1}
[junit4] 2> 974010 T3633 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974010 T3633 oass.SolrIndexSearcher.<init> Opening Searcher@7762f85f[collection1] main
[junit4] 2> 974011 T3633 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 974011 T3634 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7762f85f[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974014 T3632 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 974021 T3632 oas.SolrTestCaseJ4.setUp ###Starting testBadConfigsGenerateErrors
[junit4] 2> 974060 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\'
[junit4] 2> 974073 T3632 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\solr.xml
[junit4] 2> 974081 T3632 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores
[junit4] 2> 974083 T3632 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores
[junit4] 2> 974083 T3632 oasc.CoreContainer.<init> New CoreContainer 996561684
[junit4] 2> 974083 T3632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\]
[junit4] 2> 974085 T3632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 974085 T3632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 974085 T3632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 974085 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 974085 T3632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 974085 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 974089 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 974089 T3632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 974089 T3632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 974091 T3632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 974091 T3632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 974091 T3632 oasc.CoreContainer.load Host Name: null
[junit4] 2> 974093 T3632 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores
[junit4] 2> 974095 T3632 oasc.CorePropertiesLocator.discoverUnder Found core badConfig1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\
[junit4] 2> 974096 T3632 oasc.CorePropertiesLocator.discoverUnder Found core badConfig2 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\
[junit4] 2> 974096 T3632 oasc.CorePropertiesLocator.discoverUnder Found core badSchema1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\
[junit4] 2> 974099 T3632 oasc.CorePropertiesLocator.discoverUnder Found core badSchema2 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\
[junit4] 2> 974099 T3632 oasc.CorePropertiesLocator.discoverUnder Found core core1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\
[junit4] 2> 974101 T3632 oasc.CorePropertiesLocator.discoverUnder Found core core2 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\
[junit4] 2> 974101 T3632 oasc.CorePropertiesLocator.discover Found 6 core definitions
[junit4] 2> 974107 T3636 oasc.CoreContainer.create Creating SolrCore 'badConfig1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1
[junit4] 2> 974109 T3636 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\'
[junit4] 2> 974126 T3637 oasc.CoreContainer.create Creating SolrCore 'badConfig2' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2
[junit4] 2> 974126 T3637 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\'
[junit4] 2> 974144 T3638 oasc.CoreContainer.create Creating SolrCore 'badSchema1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1
[junit4] 2> 974144 T3638 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\'
[junit4] 2> 974168 T3638 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974177 T3638 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974177 T3638 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974223 T3638 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974224 T3638 oasc.CoreContainer.recordAndThrow ERROR Unable to create core: badSchema1 Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974226 T3638 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974226 T3638 oasc.CoreContainer.create Creating SolrCore 'badSchema2' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2
[junit4] 2> 974226 T3638 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\'
[junit4] 2> 974242 T3636 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974242 T3636 oasc.CoreContainer.createFromLocal ERROR Failed to load file C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\solrconfig.xml
[junit4] 2> 974266 T3636 oasc.CoreContainer.recordAndThrow ERROR Unable to create core: badConfig1 Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974268 T3636 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974268 T3636 oasc.CoreContainer.create Creating SolrCore 'core1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1
[junit4] 2> 974268 T3636 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\'
[junit4] 2> 974265 T3638 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974281 T3638 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974281 T3638 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974308 T3636 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974313 T3638 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974313 T3638 oasc.CoreContainer.recordAndThrow ERROR Unable to create core: badSchema2 Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974313 T3638 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974313 T3638 oasc.CoreContainer.create Creating SolrCore 'core2' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2
[junit4] 2> 974313 T3638 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\'
[junit4] 2> 974333 T3638 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974347 T3638 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974347 T3638 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974350 T3638 oass.IndexSchema.readSchema [core2] Schema name=tiny
[junit4] 2> 974355 T3638 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974355 T3638 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974356 T3638 oasc.SolrCore.<init> [core2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\
[junit4] 2> 974356 T3638 oasc.SolrCore.<init> JMX monitoring not detected for core: core2
[junit4] 2> 974363 T3637 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974363 T3637 oasc.CoreContainer.createFromLocal ERROR Failed to load file C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\solrconfig.xml
[junit4] 2> 974363 T3637 oasc.CoreContainer.recordAndThrow ERROR Unable to create core: badConfig2 Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974363 T3637 oasc.SolrException.log Ignoring exception matching \QSAXParseException\E
[junit4] 2> 974366 T3636 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974368 T3636 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974372 T3636 oass.IndexSchema.readSchema [core1] Schema name=tiny
[junit4] 2> 974379 T3636 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974379 T3636 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974382 T3636 oasc.SolrCore.<init> [core1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\
[junit4] 2> 974382 T3636 oasc.SolrCore.<init> JMX monitoring not detected for core: core1
[junit4] 2> 974382 T3638 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\
[junit4] 2> 974382 T3638 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\index/
[junit4] 2> 974382 T3638 oasc.SolrCore.initIndex WARN [core2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\index' doesn't exist. Creating new index...
[junit4] 2> 974383 T3636 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\
[junit4] 2> 974383 T3638 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\index
[junit4] 2> 974383 T3638 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 974384 T3636 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\index/
[junit4] 2> 974384 T3636 oasc.SolrCore.initIndex WARN [core1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\index' doesn't exist. Creating new index...
[junit4] 2> 974385 T3636 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\index
[junit4] 2> 974385 T3636 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 974385 T3638 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@384ee70b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144424cf),segFN=segments_1,generation=1}
[junit4] 2> 974385 T3638 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974387 T3638 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974387 T3638 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974388 T3638 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974388 T3638 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974391 T3638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974393 T3638 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974393 T3638 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974394 T3638 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=31.3291015625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=3.492456112712458, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 974394 T3636 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@342448cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@187e908c),segFN=segments_1,generation=1}
[junit4] 2> 974394 T3636 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974395 T3636 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974396 T3636 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974396 T3636 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974396 T3636 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974398 T3636 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974399 T3636 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974399 T3636 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974400 T3636 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=31.3291015625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=3.492456112712458, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 974401 T3638 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@384ee70b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144424cf),segFN=segments_1,generation=1}
[junit4] 2> 974401 T3638 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974401 T3638 oass.SolrIndexSearcher.<init> Opening Searcher@7e6984ba[core2] main
[junit4] 2> 974402 T3638 oasc.CoreContainer.registerCore registering core: core2
[junit4] 2> 974402 T3636 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@342448cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@187e908c),segFN=segments_1,generation=1}
[junit4] 2> 974402 T3636 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974402 T3636 oass.SolrIndexSearcher.<init> Opening Searcher@2124dd8b[core1] main
[junit4] 2> 974404 T3636 oasc.CoreContainer.registerCore registering core: core1
[junit4] 2> 974404 T3640 oasc.SolrCore.registerSearcher [core1] Registered new searcher Searcher@2124dd8b[core1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974405 T3639 oasc.SolrCore.registerSearcher [core2] Registered new searcher Searcher@7e6984ba[core2] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974408 T3632 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 974410 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@342448cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@187e908c),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@342448cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@187e908c),segFN=segments_2,generation=2}
[junit4] 2> 974411 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 974412 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@c1e9375[core1] main
[junit4] 2> 974412 T3632 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 974414 T3640 oasc.SolrCore.registerSearcher [core1] Registered new searcher Searcher@c1e9375[core1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c7)}
[junit4] 2> ASYNC NEW_CORE C4139 name=core1 org.apache.solr.core.SolrCore@47e1a8b8
[junit4] 2> 974415 T3632 C4139 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 974418 T3632 C4139 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 974419 T3632 C4139 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 974419 T3632 C4139 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 974435 T3632 oasc.CoreContainer.create Creating SolrCore 'badConfig1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1
[junit4] 2> 974435 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\'
[junit4] 2> 974466 T3632 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974476 T3632 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974478 T3632 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974481 T3632 oass.IndexSchema.readSchema [badConfig1] Schema name=tiny
[junit4] 2> 974489 T3632 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974489 T3632 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974491 T3632 oasc.SolrCore.<init> [badConfig1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\
[junit4] 2> 974491 T3632 oasc.SolrCore.<init> JMX monitoring not detected for core: badConfig1
[junit4] 2> 974492 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\
[junit4] 2> 974492 T3632 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\index/
[junit4] 2> 974492 T3632 oasc.SolrCore.initIndex WARN [badConfig1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\index' doesn't exist. Creating new index...
[junit4] 2> 974493 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\index
[junit4] 2> 974493 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=23.8701171875, floorSegmentMB=1.97265625, forceMergeDeletesPctAllowed=19.392425517251652, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 974494 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d5a8fb5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@214c9e8d),segFN=segments_1,generation=1}
[junit4] 2> 974495 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974496 T3632 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974497 T3632 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974497 T3632 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974498 T3632 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974499 T3632 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974501 T3632 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974501 T3632 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974502 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@30a97117
[junit4] 2> 974502 T3632 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d5a8fb5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@214c9e8d),segFN=segments_1,generation=1}
[junit4] 2> 974502 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974502 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@452c5f43[badConfig1] main
[junit4] 2> 974504 T3632 oasc.SolrCores.putTransientCore Opening transient core badConfig1
[junit4] 2> 974504 T3632 oasc.CoreContainer.registerCore registering core: badConfig1
[junit4] 2> 974504 T3632 oasc.CoreContainer.create Creating SolrCore 'badConfig2' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2
[junit4] 2> 974504 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\'
[junit4] 2> 974532 T3642 oasc.SolrCore.registerSearcher [badConfig1] Registered new searcher Searcher@452c5f43[badConfig1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974533 T3632 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974544 T3632 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974546 T3632 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974550 T3632 oass.IndexSchema.readSchema [badConfig2] Schema name=tiny
[junit4] 2> 974553 T3632 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974557 T3632 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974558 T3632 oasc.SolrCore.<init> [badConfig2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\
[junit4] 2> 974559 T3632 oasc.SolrCore.<init> JMX monitoring not detected for core: badConfig2
[junit4] 2> 974565 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\
[junit4] 2> 974565 T3632 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\index/
[junit4] 2> 974565 T3632 oasc.SolrCore.initIndex WARN [badConfig2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\index' doesn't exist. Creating new index...
[junit4] 2> 974567 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\index
[junit4] 2> 974567 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=43.361328125, floorSegmentMB=1.8369140625, forceMergeDeletesPctAllowed=7.834292876028792, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 974567 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@3df3119b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5968ef4d),segFN=segments_1,generation=1}
[junit4] 2> 974567 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974570 T3632 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974571 T3632 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974571 T3632 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974571 T3632 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974573 T3632 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974574 T3632 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974574 T3632 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974575 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=64.650390625, floorSegmentMB=0.2294921875, forceMergeDeletesPctAllowed=19.853350386584474, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5353660525260611
[junit4] 2> 974575 T3632 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@3df3119b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5968ef4d),segFN=segments_1,generation=1}
[junit4] 2> 974575 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974575 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@5bd99ec1[badConfig2] main
[junit4] 2> 974577 T3632 oasc.SolrCores.putTransientCore Opening transient core badConfig2
[junit4] 2> 974577 T3632 oasc.CoreContainer.registerCore registering core: badConfig2
[junit4] 2> 974577 T3632 oasc.CoreContainer.create Creating SolrCore 'badSchema1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1
[junit4] 2> 974577 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\'
[junit4] 2> 974606 T3644 oasc.SolrCore.registerSearcher [badConfig2] Registered new searcher Searcher@5bd99ec1[badConfig2] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974607 T3632 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974615 T3632 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974615 T3632 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974619 T3632 oass.IndexSchema.readSchema [badSchema1] Schema name=tiny
[junit4] 2> 974622 T3632 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974622 T3632 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974625 T3632 oasc.SolrCore.<init> [badSchema1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\
[junit4] 2> 974625 T3632 oasc.SolrCore.<init> JMX monitoring not detected for core: badSchema1
[junit4] 2> 974625 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\
[junit4] 2> 974625 T3632 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\index/
[junit4] 2> 974625 T3632 oasc.SolrCore.initIndex WARN [badSchema1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\index' doesn't exist. Creating new index...
[junit4] 2> 974626 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\index
[junit4] 2> 974627 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 974627 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@103c35d7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47da9c16),segFN=segments_1,generation=1}
[junit4] 2> 974627 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974630 T3632 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974630 T3632 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974630 T3632 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974631 T3632 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974633 T3632 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974633 T3632 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974633 T3632 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974634 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=40.279296875, floorSegmentMB=0.2119140625, forceMergeDeletesPctAllowed=22.25355958418138, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 974635 T3632 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@103c35d7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47da9c16),segFN=segments_1,generation=1}
[junit4] 2> 974635 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974635 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@4a738a42[badSchema1] main
[junit4] 2> 974636 T3632 oasc.SolrCores.putTransientCore Opening transient core badSchema1
[junit4] 2> 974636 T3632 oasc.CoreContainer.registerCore registering core: badSchema1
[junit4] 2> 974636 T3632 oasc.CoreContainer.create Creating SolrCore 'badSchema2' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2
[junit4] 2> 974636 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\'
[junit4] 2> 974650 T3646 oasc.SolrCore.registerSearcher [badSchema1] Registered new searcher Searcher@4a738a42[badSchema1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974665 T3632 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 974674 T3632 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 974675 T3632 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 974684 T3632 oass.IndexSchema.readSchema [badSchema2] Schema name=tiny
[junit4] 2> 974687 T3632 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974687 T3632 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974690 T3632 oasc.SolrCore.<init> [badSchema2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\
[junit4] 2> 974690 T3632 oasc.SolrCore.<init> JMX monitoring not detected for core: badSchema2
[junit4] 2> 974690 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\
[junit4] 2> 974690 T3632 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\index/
[junit4] 2> 974690 T3632 oasc.SolrCore.initIndex WARN [badSchema2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\index' doesn't exist. Creating new index...
[junit4] 2> 974692 T3632 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\index
[junit4] 2> 974693 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 974693 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7baffe20 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17af916e),segFN=segments_1,generation=1}
[junit4] 2> 974693 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974695 T3632 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974696 T3632 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 974696 T3632 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974696 T3632 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 974698 T3632 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974699 T3632 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 974699 T3632 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 974700 T3632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@58fa111f
[junit4] 2> 974700 T3632 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7baffe20 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17af916e),segFN=segments_1,generation=1}
[junit4] 2> 974700 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 974700 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@e3e4ee1[badSchema2] main
[junit4] 2> 974702 T3632 oasc.SolrCores.putTransientCore Opening transient core badSchema2
[junit4] 2> 974702 T3632 oasc.CoreContainer.registerCore registering core: badSchema2
[junit4] 2> 974703 T3648 oasc.SolrCore.registerSearcher [badSchema2] Registered new searcher Searcher@e3e4ee1[badSchema2] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 974704 T3632 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 974704 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@342448cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@187e908c),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@342448cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@187e908c),segFN=segments_3,generation=3}
[junit4] 2> 974704 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 974707 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@791febd8[core1] main
[junit4] 2> 974707 T3640 oasc.SolrCore.registerSearcher [core1] Registered new searcher Searcher@791febd8[core1] main{StandardDirectoryReader(segments_3:7:nrt _1(4.7):c7)}
[junit4] 2> 974708 T3632 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> ASYNC NEW_CORE C4140 name=core1 org.apache.solr.core.SolrCore@47e1a8b8
[junit4] 2> 974709 T3632 C4140 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 974710 T3632 C4140 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 974711 T3632 C4140 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 974712 T3632 C4140 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 974712 T3632 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 974715 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d5a8fb5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@214c9e8d),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d5a8fb5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@214c9e8d),segFN=segments_2,generation=2}
[junit4] 2> 974715 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 974716 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@6f032525[badConfig1] main
[junit4] 2> 974718 T3642 oasc.SolrCore.registerSearcher [badConfig1] Registered new searcher Searcher@6f032525[badConfig1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c7)}
[junit4] 2> 974719 T3632 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> ASYNC NEW_CORE C4141 name=badConfig1 org.apache.solr.core.SolrCore@5a2d9efb
[junit4] 2> 974719 T3632 C4141 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C4142 name=badConfig1 org.apache.solr.core.SolrCore@5a2d9efb
[junit4] 2> 974725 T3632 C4142 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 974726 T3632 C4142 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 974727 T3632 C4142 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 974727 T3632 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 974730 T3632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@103c35d7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47da9c16),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@103c35d7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47da9c16),segFN=segments_2,generation=2}
[junit4] 2> 974730 T3632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 974731 T3632 oass.SolrIndexSearcher.<init> Opening Searcher@3ef07b58[badSchema1] main
[junit4] 2> 974731 T3632 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 974733 T3646 oasc.SolrCore.registerSearcher [badSchema1] Registered new searcher Searcher@3ef07b58[badSchema1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c7)}
[junit4] 2> ASYNC NEW_CORE C4143 name=badSchema1 org.apache.solr.core.SolrCore@317db71c
[junit4] 2> 974733 T3632 C4143 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 974734 T3632 C4143 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 974735 T3632 C4143 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 974736 T3632 C4143 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 974736 T3632 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=996561684
[junit4] 2> 974736 T3632 oasc.SolrCore.close [core2] CLOSING SolrCore org.apache.solr.core.SolrCore@555127e6
[junit4] 2> 974737 T3632 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 974737 T3632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 974737 T3632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 974737 T3632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 974739 T3632 oasc.SolrCore.closeSearcher [core2] Closing main searcher on request.
[junit4] 2> 974739 T3632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 974739 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\index;done=false>>]
[junit4] 2> 974739 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\index
[junit4] 2> 974739 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\;done=false>>]
[junit4] 2> 974739 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core2\data\
[junit4] 2> 974739 T3632 oasc.SolrCore.close [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@47e1a8b8
[junit4] 2> 974739 T3632 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=14,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 974739 T3632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 974739 T3632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 974739 T3632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 974742 T3632 oasc.SolrCore.closeSearcher [core1] Closing main searcher on request.
[junit4] 2> 974742 T3632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 974742 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\;done=false>>]
[junit4] 2> 974742 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\
[junit4] 2> 974742 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\index;done=false>>]
[junit4] 2> 974742 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\core1\data\index
[junit4] 2> 974742 T3632 oasc.SolrCore.close [badConfig1] CLOSING SolrCore org.apache.solr.core.SolrCore@5a2d9efb
[junit4] 2> 974742 T3632 oasu.DirectUpdateHandler2.close 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] 2> 974743 T3632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 974743 T3632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 974743 T3632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 974744 T3632 oasc.SolrCore.closeSearcher [badConfig1] Closing main searcher on request.
[junit4] 2> 974744 T3632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 974744 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\index;done=false>>]
[junit4] 2> 974744 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\index
[junit4] 2> 974744 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\;done=false>>]
[junit4] 2> 974745 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig1\data\
[junit4] 2> 974745 T3632 oasc.SolrCore.close [badConfig2] CLOSING SolrCore org.apache.solr.core.SolrCore@377b731f
[junit4] 2> 974745 T3632 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 974746 T3632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 974746 T3632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 974746 T3632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 974747 T3632 oasc.SolrCore.closeSearcher [badConfig2] Closing main searcher on request.
[junit4] 2> 974747 T3632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 974747 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\;done=false>>]
[junit4] 2> 974747 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\
[junit4] 2> 974747 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\index;done=false>>]
[junit4] 2> 974747 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badConfig2\data\index
[junit4] 2> 974749 T3632 oasc.SolrCore.close [badSchema1] CLOSING SolrCore org.apache.solr.core.SolrCore@317db71c
[junit4] 2> 974749 T3632 oasu.DirectUpdateHandler2.close 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] 2> 974749 T3632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 974749 T3632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 974749 T3632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 974751 T3632 oasc.SolrCore.closeSearcher [badSchema1] Closing main searcher on request.
[junit4] 2> 974751 T3632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 974751 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\index;done=false>>]
[junit4] 2> 974751 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\index
[junit4] 2> 974751 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\;done=false>>]
[junit4] 2> 974751 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema1\data\
[junit4] 2> 974751 T3632 oasc.SolrCore.close [badSchema2] CLOSING SolrCore org.apache.solr.core.SolrCore@a6ce413
[junit4] 2> 974751 T3632 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 974751 T3632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 974751 T3632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 974753 T3632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 974762 T3632 oasc.SolrCore.closeSearcher [badSchema2] Closing main searcher on request.
[junit4] 2> 974762 T3632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 974762 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\;done=false>>]
[junit4] 2> 974762 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\
[junit4] 2> 974762 T3632 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\index;done=false>>]
[junit4] 2> 974762 T3632 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\badSchema2\data\index
[junit4] 2> 974813 T3632 oas.SolrTestCaseJ4.tearDown ###Ending testBadConfigsGenerateErrors
[junit4] 2> 974818 T3632 oas.SolrTestCaseJ4.setUp ###Starting testRace
[junit4] 2> 974901 T3632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\'
[junit4] 2> 974933 T3632 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\solr.xml
[junit4] 2> 974950 T3632 oasc.CoreContainer.<init> New CoreContainer 1176234022
[junit4] 2> 974950 T3632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\]
[junit4] 2> 974956 T3632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 974956 T3632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 974956 T3632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 974956 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 974956 T3632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 974956 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 974958 T3632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 974958 T3632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 974958 T3632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 974963 T3632 oasc.SolrCores.allocateLazyCores Allocating transient cache for 4 transient cores
[junit4] 2> 974964 T3632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 974964 T3632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 974964 T3632 oasc.CoreContainer.load Host Name: null
[junit4] 2> 974980 T3650 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1
[junit4] 2> 974982 T3650 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\'
[junit4] 2> 975013 T3651 oasc.CoreContainer.create Creating SolrCore 'collectionLazy2' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2
[junit4] 2> 975013 T3651 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\'
[junit4] 2> 975038 T3651 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 975044 T3650 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 975053 T3650 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 975053 T3650 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 975057 T3650 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 975061 T3650 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 975061 T3650 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 975062 T3650 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\data\
[junit4] 2> 975062 T3650 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 975063 T3652 oasc.CoreContainer.create Creating SolrCore 'collectionLazy5' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5
[junit4] 2> 975063 T3652 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\'
[junit4] 2> 975083 T3652 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 975090 T3650 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\data\
[junit4] 2> 975098 T3652 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 975098 T3652 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 975102 T3652 oass.IndexSchema.readSchema [collectionLazy5] Schema name=tiny
[junit4] 2> 975104 T3652 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 975104 T3652 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 975106 T3652 oasc.SolrCore.<init> [collectionLazy5] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\data\
[junit4] 2> 975106 T3652 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy5
[junit4] 2> 975107 T3650 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\data\index/
[junit4] 2> 975107 T3650 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 975107 T3650 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection1\data\index
[junit4] 2> 975107 T3650 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 975107 T3652 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\data\
[junit4] 2> 975107 T3652 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\data\index/
[junit4] 2> 975107 T3652 oasc.SolrCore.initIndex WARN [collectionLazy5] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\data\index' doesn't exist. Creating new index...
[junit4] 2> 975108 T3652 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection5\data\index
[junit4] 2> 975109 T3652 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 975109 T3650 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f78ef48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@422e5d4f),segFN=segments_1,generation=1}
[junit4] 2> 975109 T3650 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975110 T3650 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 975110 T3650 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 975110 T3650 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 975111 T3650 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 975116 T3650 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 975116 T3650 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 975116 T3650 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 975116 T3650 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=31.3291015625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=3.492456112712458, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 975117 T3652 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@541b4da9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e8b419e),segFN=segments_1,generation=1}
[junit4] 2> 975117 T3652 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975118 T3652 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 975118 T3652 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 975118 T3652 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 975118 T3652 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 975120 T3652 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 975121 T3652 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 975121 T3652 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 975121 T3652 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=31.3291015625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=3.492456112712458, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 975121 T3650 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f78ef48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@422e5d4f),segFN=segments_1,generation=1}
[junit4] 2> 975121 T3650 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975121 T3650 oass.SolrIndexSearcher.<init> Opening Searcher@1f1048bc[collection1] main
[junit4] 2> 975123 T3652 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@541b4da9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e8b419e),segFN=segments_1,generation=1}
[junit4] 2> 975124 T3652 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975124 T3652 oass.SolrIndexSearcher.<init> Opening Searcher@c43d278[collectionLazy5] main
[junit4] 2> 975124 T3650 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 975124 T3654 oasc.SolrCore.registerSearcher [collectionLazy5] Registered new searcher Searcher@c43d278[collectionLazy5] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 975124 T3652 oasc.CoreContainer.registerCore registering core: collectionLazy5
[junit4] 2> 975133 T3653 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f1048bc[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 975136 T3651 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 975138 T3651 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 975142 T3651 oass.IndexSchema.readSchema [collectionLazy2] Schema name=tiny
[junit4] 2> 975149 T3651 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 975149 T3651 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 975150 T3651 oasc.SolrCore.<init> [collectionLazy2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\data\
[junit4] 2> 975150 T3651 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy2
[junit4] 2> 975151 T3651 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\data\
[junit4] 2> 975151 T3651 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\data\index/
[junit4] 2> 975151 T3651 oasc.SolrCore.initIndex WARN [collectionLazy2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\data\index' doesn't exist. Creating new index...
[junit4] 2> 975152 T3651 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\TestLazyCores\collection2\data\index
[junit4] 2> 975153 T3651 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 975153 T3651 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43ef2bb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ac16c73),segFN=segments_1,generation=1}
[junit4] 2> 975153 T3651 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 975153 T3651 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 975153 T3651 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 975154 T3651 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 975154 T3651 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 975156 T3651 oashl.XMLLoader.init x
[...truncated too long message...]
31 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
[junit4] 2> 1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 160001 T3631 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> 163005 T3631 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> NOTE: test params are: codec=Lucene46: {v_t=PostingsFormat(name=FSTPulsing41), id=PostingsFormat(name=FSTPulsing41), v_s=PostingsFormat(name=FSTOrdPulsing41), v_f=PostingsFormat(name=TestBloomFilteredLucene41Postings)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=iw_IL, timezone=America/Danmarkshavn
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0 (64-bit)/cpus=2,threads=2,free=112272672,total=275365888
[junit4] 2> NOTE: All tests run in this JVM: [LeaderElectionIntegrationTest, AssignTest, ShowFileRequestHandlerTest, TestCSVResponseWriter, SystemInfoHandlerTest, TestCloudManagedSchemaAddField, TestLRUCache, TestSolrQueryParser, ClusterStateTest, SpellingQueryConverterTest, TestHighlightDedupGrouping, TestArbitraryIndexDir, IndexSchemaTest, TestFastLRUCache, BasicFunctionalityTest, ParsingFieldUpdateProcessorsTest, TestOverriddenPrefixQueryForCustomFieldType, AddBlockUpdateTest, TestDistributedSearch, OverseerRolesTest, StatelessScriptUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, TestSolrQueryParserResource, SolrPluginUtilsTest, TestSolrXMLSerializer, BlockCacheTest, TestSolrXmlPersistence, TermsComponentTest, TestCharFilters, FieldAnalysisRequestHandlerTest, HighlighterConfigTest, UpdateRequestProcessorFactoryTest, TestSweetSpotSimilarityFactory, TestFieldCollectionResource, DocValuesMultiTest, TestRecoveryHdfs, SolrIndexSplitterTest, DistributedTermsComponentTest, FullSolrCloudDistribCmdsTest, SynonymTokenizerTest, UpdateParamsTest, TestHighFrequencyDictionaryFactory, UnloadDistributedZkTest, TestCoreDiscovery, SchemaVersionSpecificBehaviorTest, DistributedQueryComponentCustomSortTest, SpellCheckComponentTest, OpenExchangeRatesOrgProviderTest, TermVectorComponentDistributedTest, TestSchemaSimilarityResource, OutputWriterTest, TestRealTimeGet, HdfsCollectionsAPIDistributedZkTest, TestGroupingSearch, TriLevelCompositeIdRoutingTest, XmlUpdateRequestHandlerTest, TestCodecSupport, BadIndexSchemaTest, PluginInfoTest, CoreAdminHandlerTest, DistribCursorPagingTest, DisMaxRequestHandlerTest, LukeRequestHandlerTest, TestRandomDVFaceting, LeaderElectionTest, DistributedSpellCheckComponentTest, TestRangeQuery, CursorPagingTest, PathHierarchyTokenizerFactoryTest, DirectUpdateHandlerOptimizeTest, TestNumberUtils, TestOmitPositions, TestPostingsSolrHighlighter, ChangedSchemaMergeTest, FileUtilsTest, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, SolrTestCaseJ4Test, TestIBSimilarityFactory, DistributedDebugComponentTest, TestSolrXml, TestSolrJ, TestSimpleQParserPlugin, TestFieldResource, TestUpdate, ConvertedLegacyTest, DeleteReplicaTest, BinaryUpdateRequestHandlerTest, TestSearchPerf, TestStressReorder, CurrencyFieldXmlFileTest, CursorMarkTest, TestFaceting, DistanceFunctionTest, TestFieldTypeResource, TestSuggestSpellingConverter, TestFastOutputStream, RecoveryZkTest, CircularListTest, TestQueryUtils, TestHashPartitioner, ScriptEngineTest, NumericFieldsTest, PrimUtilsTest, TestLMJelinekMercerSimilarityFactory, TestDocBasedVersionConstraints, URLClassifyProcessorTest, TestQuerySenderListener, TestCSVLoader, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=10CD8A71840EF02C -Dtests.slow=true -Dtests.locale=iw_IL -Dtests.timezone=America/Danmarkshavn -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s | TestLazyCores (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=59 closes=58
[junit4] > at __randomizedtesting.SeedInfo.seed([10CD8A71840EF02C]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:335)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:139)
[junit4] > at java.lang.Thread.run(Thread.java:744)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
[junit4] > 1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > at __randomizedtesting.SeedInfo.seed([10CD8A71840EF02C]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=3678, name=searcherExecutor-2090-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > at __randomizedtesting.SeedInfo.seed([10CD8A71840EF02C]:0)
[junit4] Completed in 163.06s, 8 tests, 1 failure, 2 errors <<< FAILURES!
[...truncated 756 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:459: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:439: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1308: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:941: There were test failures: 362 suites, 1562 tests, 3 suite-level errors, 1054 ignored (1 assumption)
Total time: 47 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-fcs-b129 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_45) - Build #
3702 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3702/
Java: 32bit/jdk1.6.0_45 -client -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.CustomCollectionTest.testDistribSearch
Error Message:
Could not find the new collection - 503 : http://127.0.0.1:63447/implicitcollwithShardField
Stack Trace:
java.lang.AssertionError: Could not find the new collection - 503 : http://127.0.0.1:63447/implicitcollwithShardField
at __randomizedtesting.SeedInfo.seed([551813011B59C807:D4FE9D196C06A83B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForNon403or404or503(AbstractFullDistribZkTestBase.java:1821)
at org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:331)
at org.apache.solr.cloud.CustomCollectionTest.doTest(CustomCollectionTest.java:131)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
at sun.reflect.GeneratedMethodAccessor97.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:662)
Build Log:
[...truncated 10250 lines...]
[junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
[junit4] 2> 2951841 T8736 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2951846 T8736 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-CustomCollectionTest-1392363218907
[junit4] 2> 2951849 T8736 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2951850 T8737 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2951959 T8736 oasc.ZkTestServer.run start zk server on port:63410
[junit4] 2> 2951961 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2951968 T8743 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1798496 name:ZooKeeperConnection Watcher:127.0.0.1:63410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2951969 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2951969 T8736 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2951979 T8738 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1442f50a7e60000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2951985 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2951988 T8745 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a18ff3 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2951988 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2951988 T8736 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2952002 T8736 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2952010 T8736 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2952018 T8736 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2952025 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2952026 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2952040 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2952043 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2952056 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2952056 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2952065 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2952066 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2952077 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2952077 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2952092 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2952092 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2952101 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2952101 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2952110 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2952111 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2952123 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2952123 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2952135 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2952136 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2952145 T8736 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2952147 T8736 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2952815 T8736 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2952823 T8736 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63417
[junit4] 2> 2952824 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2952824 T8736 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2952825 T8736 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217
[junit4] 2> 2952825 T8736 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\'
[junit4] 2> 2952865 T8736 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\solr.xml
[junit4] 2> 2952992 T8736 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217
[junit4] 2> 2952992 T8736 oasc.CoreContainer.<init> New CoreContainer 16914180
[junit4] 2> 2952994 T8736 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\]
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2952998 T8736 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2953001 T8736 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2953005 T8736 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2953005 T8736 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2953005 T8736 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2953005 T8736 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63410/solr
[junit4] 2> 2953005 T8736 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2953009 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2953014 T8756 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@350926 name:ZooKeeperConnection Watcher:127.0.0.1:63410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2953014 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2953020 T8738 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1442f50a7e60002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2953021 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2953026 T8758 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9ce096 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2953026 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2953030 T8736 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2953041 T8736 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2953052 T8736 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2953058 T8736 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63417_
[junit4] 2> 2953061 T8736 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63417_
[junit4] 2> 2953073 T8736 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 2953080 T8736 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2953094 T8736 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:63417_
[junit4] 2> 2953095 T8736 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2953103 T8736 oasc.Overseer.start Overseer (id=91249915915010051-127.0.0.1:63417_-n_0000000000) starting
[junit4] 2> 2953112 T8736 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2953125 T8760 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2953128 T8736 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2953129 T8760 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 2953138 T8736 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2953144 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2953154 T8759 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2953160 T8736 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217
[junit4] 2> 2953460 T8736 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\conf\
[junit4] 2> 2953473 T8736 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2953475 T8736 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2953475 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2953477 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2953482 T8763 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fe5144 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2953483 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2953485 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2953495 T8736 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2954266 T8736 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 2954268 T8736 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2954272 T8736 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63429
[junit4] 2> 2954273 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2954274 T8736 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2954274 T8736 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554
[junit4] 2> 2954275 T8736 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554\'
[junit4] 2> 2954311 T8736 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554\solr.xml
[junit4] 2> 2954418 T8736 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554
[junit4] 2> 2954418 T8736 oasc.CoreContainer.<init> New CoreContainer 29108041
[junit4] 2> 2954419 T8736 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554\]
[junit4] 2> 2954421 T8736 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2954422 T8736 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2954422 T8736 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2954422 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2954423 T8736 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2954423 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2954425 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2954425 T8736 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2954426 T8736 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2954430 T8736 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2954432 T8736 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2954432 T8736 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2954433 T8736 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63410/solr
[junit4] 2> 2954434 T8736 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2954436 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2954447 T8774 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b764fb name:ZooKeeperConnection Watcher:127.0.0.1:63410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2954448 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2954456 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2954460 T8776 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5dfb6e name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2954462 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2954491 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2955687 T8736 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63429_
[junit4] 2> 2955692 T8736 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63429_
[junit4] 2> 2955703 T8758 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2955704 T8763 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2955705 T8776 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2955723 T8736 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554
[junit4] 2> 2956039 T8736 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554\conf\
[junit4] 2> 2956053 T8736 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2956054 T8736 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2956055 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2956708 T8736 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 2956711 T8736 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2956714 T8736 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63438
[junit4] 2> 2956715 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2956715 T8736 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2956716 T8736 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115
[junit4] 2> 2956716 T8736 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\'
[junit4] 2> 2956755 T8736 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\solr.xml
[junit4] 2> 2956865 T8736 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115
[junit4] 2> 2956866 T8736 oasc.CoreContainer.<init> New CoreContainer 4739448
[junit4] 2> 2956866 T8736 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\]
[junit4] 2> 2956868 T8736 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2956869 T8736 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2956869 T8736 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2956870 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2956870 T8736 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2956872 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2956872 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2956873 T8736 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2956873 T8736 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2956879 T8736 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2956879 T8736 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2956879 T8736 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2956880 T8736 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63410/solr
[junit4] 2> 2956881 T8736 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2956882 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2956890 T8788 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1dcca8f name:ZooKeeperConnection Watcher:127.0.0.1:63410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2956890 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2956896 T8738 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1442f50a7e60007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2956897 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2956902 T8790 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c2ab08 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2956902 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2956914 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2958111 T8736 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63438_
[junit4] 2> 2958114 T8736 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63438_
[junit4] 2> 2958128 T8776 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2958128 T8758 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2958128 T8763 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2958129 T8790 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2958146 T8736 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115
[junit4] 2> 2958455 T8736 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\conf\
[junit4] 2> 2958469 T8736 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2958471 T8736 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2958471 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2959109 T8736 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 2959111 T8736 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2959115 T8736 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63447
[junit4] 2> 2959116 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2959116 T8736 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2959117 T8736 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531
[junit4] 2> 2959117 T8736 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\'
[junit4] 2> 2959154 T8736 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\solr.xml
[junit4] 2> 2959277 T8736 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531
[junit4] 2> 2959277 T8736 oasc.CoreContainer.<init> New CoreContainer 31377957
[junit4] 2> 2959279 T8736 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\]
[junit4] 2> 2959282 T8736 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2959282 T8736 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2959282 T8736 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2959282 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2959282 T8736 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2959285 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2959285 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2959285 T8736 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2959285 T8736 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2959290 T8736 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2959290 T8736 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2959290 T8736 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2959290 T8736 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63410/solr
[junit4] 2> 2959292 T8736 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2959294 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2959307 T8802 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a94939 name:ZooKeeperConnection Watcher:127.0.0.1:63410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2959308 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2959315 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2959320 T8804 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19aefd5 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2959321 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2959334 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2960531 T8736 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63447_
[junit4] 2> 2960536 T8736 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63447_
[junit4] 2> 2960547 T8763 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2960549 T8790 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2960549 T8776 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2960551 T8804 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2960552 T8758 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2960572 T8736 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531
[junit4] 2> 2960878 T8736 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\conf\
[junit4] 2> 2960894 T8736 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2960895 T8736 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2960896 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2961554 T8736 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 2961555 T8736 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2961560 T8736 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63456
[junit4] 2> 2961561 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2961562 T8736 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2961562 T8736 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955
[junit4] 2> 2961563 T8736 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\'
[junit4] 2> 2961601 T8736 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\solr.xml
[junit4] 2> 2961706 T8736 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955
[junit4] 2> 2961707 T8736 oasc.CoreContainer.<init> New CoreContainer 12789425
[junit4] 2> 2961708 T8736 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\]
[junit4] 2> 2961710 T8736 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2961710 T8736 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2961711 T8736 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2961711 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2961713 T8736 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2961713 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2961714 T8736 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2961714 T8736 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2961715 T8736 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2961721 T8736 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2961721 T8736 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2961722 T8736 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2961722 T8736 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63410/solr
[junit4] 2> 2961723 T8736 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2961725 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2961743 T8816 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2151bb name:ZooKeeperConnection Watcher:127.0.0.1:63410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2961744 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2961751 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2961754 T8818 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e66131 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2961755 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2961768 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2962963 T8736 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63456_
[junit4] 2> 2962968 T8736 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63456_
[junit4] 2> 2962979 T8763 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2962981 T8758 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2962982 T8790 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2962984 T8776 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2962984 T8818 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2962985 T8804 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2963004 T8736 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955
[junit4] 2> 2963281 T8736 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\conf\
[junit4] 2> 2963300 T8736 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2963303 T8736 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2963303 T8736 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2963306 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2963311 T8821 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@116a9e6 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2963312 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2963315 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2963329 T8736 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2963334 T8823 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b40b5 name:ZooKeeperConnection Watcher:127.0.0.1:63410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2963335 T8736 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2963340 T8736 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2963352 T8812 oasha.CollectionsHandler.handleCreateAction Creating Collection : shards=a,b,c&maxShardsPerNode=3&name=implicitcoll0&replicationFactor=3&action=CREATE&wt=javabin&router.name=implicit&version=2
[junit4] 2> 2963356 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963364 T8760 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"implicitcoll0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "shards":"a,b,c",
[junit4] 2> "router.name":"implicit"}
[junit4] 2> 2963364 T8760 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"implicitcoll0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "shards":"a,b,c",
[junit4] 2> "router.name":"implicit"}
[junit4] 2> 2963389 T8760 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1
[junit4] 2> 2963389 T8760 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/implicitcoll0
[junit4] 2> 2963389 T8760 oascc.SolrZkClient.makePath makePath: /collections/implicitcoll0
[junit4] 2> 2963402 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963408 T8759 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2963409 T8759 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: implicitcoll0
[junit4] 2> 2963409 T8759 oasc.Overseer$ClusterStateUpdater.createCollection Create collection implicitcoll0 with shards [a, b, c]
[junit4] 2> 2963417 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963420 T8790 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963421 T8776 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963421 T8804 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963421 T8818 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963423 T8823 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963423 T8821 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963424 T8763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963426 T8758 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963523 T8760 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [a, b, c] , repFactor : 3
[junit4] 2> 2963523 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_a_replica1 as part of slice a of collection implicitcoll0 on 127.0.0.1:63417_
[junit4] 2> 2963524 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_a_replica2 as part of slice a of collection implicitcoll0 on 127.0.0.1:63447_
[junit4] 2> 2963524 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_a_replica3 as part of slice a of collection implicitcoll0 on 127.0.0.1:63438_
[junit4] 2> 2963528 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_b_replica1 as part of slice b of collection implicitcoll0 on 127.0.0.1:63456_
[junit4] 2> 2963531 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_b_replica2 as part of slice b of collection implicitcoll0 on 127.0.0.1:63429_
[junit4] 2> 2963534 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_b_replica3 as part of slice b of collection implicitcoll0 on 127.0.0.1:63417_
[junit4] 2> 2963535 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_c_replica1 as part of slice c of collection implicitcoll0 on 127.0.0.1:63447_
[junit4] 2> 2963535 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_c_replica2 as part of slice c of collection implicitcoll0 on 127.0.0.1:63438_
[junit4] 2> 2963535 T8798 oasc.ZkController.publish publishing core=implicitcoll0_a_replica2 state=down
[junit4] 2> 2963538 T8760 oasc.OverseerCollectionProcessor.createCollection Creating shard implicitcoll0_c_replica3 as part of slice c of collection implicitcoll0 on 127.0.0.1:63456_
[junit4] 2> 2963539 T8783 oasc.ZkController.publish publishing core=implicitcoll0_a_replica3 state=down
[junit4] 2> 2963549 T8768 oasc.ZkController.publish publishing core=implicitcoll0_b_replica2 state=down
[junit4] 2> 2963553 T8809 oasc.ZkController.publish publishing core=implicitcoll0_c_replica3 state=down
[junit4] 2> 2963554 T8798 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963549 T8813 oasc.ZkController.publish publishing core=implicitcoll0_b_replica1 state=down
[junit4] 2> 2963542 T8753 oasc.ZkController.publish publishing core=implicitcoll0_a_replica1 state=down
[junit4] 2> 2963564 T8809 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963562 T8784 oasc.ZkController.publish publishing core=implicitcoll0_c_replica2 state=down
[junit4] 2> 2963560 T8768 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963567 T8813 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963560 T8783 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963559 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963569 T8753 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963571 T8784 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963554 T8799 oasc.ZkController.publish publishing core=implicitcoll0_c_replica1 state=down
[junit4] 2> 2963564 T8754 oasc.ZkController.publish publishing core=implicitcoll0_b_replica3 state=down
[junit4] 2> 2963578 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963579 T8754 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963581 T8759 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2963581 T8799 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2963583 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63447",
[junit4] 2> "core":"implicitcoll0_a_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63447_",
[junit4] 2> "shard":"a",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963596 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63438",
[junit4] 2> "core":"implicitcoll0_a_replica3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63438_",
[junit4] 2> "shard":"a",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963605 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963608 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63429",
[junit4] 2> "core":"implicitcoll0_b_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63429_",
[junit4] 2> "shard":"b",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963616 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963620 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63456",
[junit4] 2> "core":"implicitcoll0_c_replica3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63456_",
[junit4] 2> "shard":"c",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963628 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963635 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63456",
[junit4] 2> "core":"implicitcoll0_b_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63456_",
[junit4] 2> "shard":"b",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963643 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963648 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63417",
[junit4] 2> "core":"implicitcoll0_a_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63417_",
[junit4] 2> "shard":"a",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963656 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963659 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63438",
[junit4] 2> "core":"implicitcoll0_c_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63438_",
[junit4] 2> "shard":"c",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963667 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963672 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63417",
[junit4] 2> "core":"implicitcoll0_b_replica3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63417_",
[junit4] 2> "shard":"b",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963680 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963684 T8759 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63447",
[junit4] 2> "core":"implicitcoll0_c_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63447_",
[junit4] 2> "shard":"c",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"implicitcoll0",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2963691 T8758 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2963818 T8790 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963819 T8804 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963819 T8818 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963819 T8776 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963820 T8823 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963821 T8758 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963821 T8821 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2963823 T8763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2964664 T8798 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_a_replica2
[junit4] 2> 2964667 T8798 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_a_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\implicitcoll0_a_replica2
[junit4] 2> 2964667 T8798 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964669 T8798 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964669 T8798 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964672 T8798 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964672 T8798 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\implicitcoll0_a_replica2\'
[junit4] 2> 2964673 T8809 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_c_replica3
[junit4] 2> 2964675 T8768 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_b_replica2
[junit4] 2> 2964677 T8809 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_c_replica3' using instanceDir: .\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\implicitcoll0_c_replica3
[junit4] 2> 2964677 T8809 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964679 T8813 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_b_replica1
[junit4] 2> 2964679 T8783 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_a_replica3
[junit4] 2> 2964681 T8753 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_a_replica1
[junit4] 2> 2964684 T8783 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_a_replica3' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_a_replica3
[junit4] 2> 2964684 T8783 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964688 T8754 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_b_replica3
[junit4] 2> 2964688 T8753 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_a_replica1' using instanceDir: .\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_a_replica1
[junit4] 2> 2964688 T8753 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964689 T8754 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_b_replica3' using instanceDir: .\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3
[junit4] 2> 2964689 T8754 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964691 T8768 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_b_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554\implicitcoll0_b_replica2
[junit4] 2> 2964691 T8768 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964693 T8784 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_c_replica2
[junit4] 2> 2964695 T8784 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_c_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2
[junit4] 2> 2964696 T8784 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964698 T8754 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964698 T8754 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964701 T8784 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964701 T8784 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964701 T8768 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964702 T8768 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964691 T8799 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for implicitcoll0_c_replica1
[junit4] 2> 2964705 T8799 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_c_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\implicitcoll0_c_replica1
[junit4] 2> 2964707 T8799 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964689 T8813 oasc.CoreContainer.create Creating SolrCore 'implicitcoll0_b_replica1' using instanceDir: .\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\implicitcoll0_b_replica1
[junit4] 2> 2964708 T8813 oasc.ZkController.createCollectionZkNode Check for collection zkNode:implicitcoll0
[junit4] 2> 2964698 T8753 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964710 T8753 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964698 T8783 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964712 T8783 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964698 T8809 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964714 T8809 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964719 T8754 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964719 T8754 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\'
[junit4] 2> 2964722 T8799 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964722 T8799 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964724 T8813 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2964725 T8813 oascc.ZkStateReader.readConfigName Load collection config from:/collections/implicitcoll0
[junit4] 2> 2964732 T8783 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964733 T8783 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_a_replica3\'
[junit4] 2> 2964740 T8784 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964741 T8784 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\'
[junit4] 2> 2964756 T8768 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964756 T8768 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty1-1392363220554\implicitcoll0_b_replica2\'
[junit4] 2> 2964778 T8799 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964778 T8799 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty3-1392363225531\implicitcoll0_c_replica1\'
[junit4] 2> 2964794 T8753 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964795 T8753 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_a_replica1\'
[junit4] 2> 2964801 T8809 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964801 T8809 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\implicitcoll0_c_replica3\'
[junit4] 2> 2964804 T8813 oascc.ZkStateReader.readConfigName path=/collections/implicitcoll0 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2964804 T8813 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CustomCollectionTest-jetty4-1392363227955\implicitcoll0_b_replica1\'
[junit4] 2> 2964885 T8754 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2964936 T8798 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2965032 T8783 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2965042 T8784 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2965127 T8783 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965140 T8784 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965147 T8754 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965215 T8768 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2965251 T8799 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2965304 T8784 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965315 T8784 oass.IndexSchema.readSchema [implicitcoll0_c_replica2] Schema name=test
[junit4] 2> 2965367 T8799 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965398 T8768 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965453 T8783 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965469 T8783 oass.IndexSchema.readSchema [implicitcoll0_a_replica3] Schema name=test
[junit4] 2> 2965518 T8798 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965633 T8809 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2965748 T8809 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2965783 T8768 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965786 T8754 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965789 T8799 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965796 T8798 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965798 T8809 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2965830 T8768 oass.IndexSchema.readSchema [implicitcoll0_b_replica2] Schema name=test
[junit4] 2> 2965839 T8754 oass.IndexSchema.readSchema [implicitcoll0_b_replica3] Schema name=test
[junit4] 2> 2965840 T8799 oass.IndexSchema.readSchema [implicitcoll0_c_replica1] Schema name=test
[junit4] 2> 2965847 T8798 oass.IndexSchema.readSchema [implicitcoll0_a_replica2] Schema name=test
[junit4] 2> 2965847 T8809 oass.IndexSchema.readSchema [implicitcoll0_c_replica3] Schema name=test
[junit4] 2> 2966061 T8813 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2966061 T8753 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2966296 T8813 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2966492 T8753 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2966849 T8753 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2966861 T8753 oass.IndexSchema.readSchema [implicitcoll0_a_replica1] Schema name=test
[junit4] 2> 2966849 T8813 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2968602 T8813 oass.IndexSchema.readSchema [implicitcoll0_b_replica1] Schema name=test
[junit4] 2> 2969019 T8784 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969019 T8784 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969024 T8784 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2969107 T8784 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2969113 T8784 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2969219 T8754 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969222 T8754 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969223 T8754 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2969230 T8754 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2969238 T8754 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2969413 T8809 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969415 T8809 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969698 T8783 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969700 T8783 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969868 T8798 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969868 T8798 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969892 T8768 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969892 T8768 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2969894 T8768 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2969901 T8768 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2969907 T8768 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2969913 T8798 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2969920 T8798 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2969925 T8783 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2969925 T8809 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2969956 T8798 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2970025 T8799 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2970028 T8799 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2970036 T8783 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2970042 T8783 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2970054 T8799 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2970063 T8799 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2970070 T8799 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2970085 T8809 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2970092 T8809 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2970199 T8753 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2970201 T8753 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2970203 T8753 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2970211 T8753 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2970216 T8753 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2970323 T8754 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970339 T8784 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970353 T8754 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970371 T8784 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970389 T8754 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970402 T8768 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970408 T8784 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970419 T8754 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2970419 T8754 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2970419 T8754 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970439 T8768 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970440 T8784 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2970440 T8784 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2970441 T8784 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970443 T8754 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2970443 T8754 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2970444 T8754 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2970445 T8754 oasc.SolrCore.<init> [implicitcoll0_b_replica3] Opening new SolrCore at .\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\, dataDir=.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\data\
[junit4] 2> 2970445 T8754 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@62c51
[junit4] 2> 2970448 T8798 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970455 T8768 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970457 T8754 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\data\
[junit4] 2> 2970457 T8754 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\data\index/
[junit4] 2> 2970458 T8754 oasc.SolrCore.initIndex WARN [implicitcoll0_b_replica3] Solr index directory '.\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\data\index' doesn't exist. Creating new index...
[junit4] 2> 2970458 T8754 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CustomCollectionTest-controljetty-1392363219217\implicitcoll0_b_replica3\data\index
[junit4] 2> 2970460 T8784 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2970460 T8784 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2970461 T8784 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2970462 T8784 oasc.SolrCore.<init> [implicitcoll0_c_replica2] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\data\
[junit4] 2> 2970462 T8784 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@62c51
[junit4] 2> 2970464 T8754 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=20.419921875, floorSegmentMB=1.2216796875, forceMergeDeletesPctAllowed=9.704870435940196, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2970464 T8784 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\data\
[junit4] 2> 2970465 T8784 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\data\index/
[junit4] 2> 2970465 T8784 oasc.SolrCore.initIndex WARN [implicitcoll0_c_replica2] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 2970467 T8768 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2970467 T8768 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2970468 T8768 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970474 T8798 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970488 T8784 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CustomCollectionTest-jetty2-1392363223115\implicitcoll0_c_replica2\data\index
[junit4] 2> 2970488 T8784 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=20.419921875, floorSegmentMB=1.2216796875, forceMergeDeletesPctAllowed=9.704870435940196, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2970491 T8784 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@47a73a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a84eee),segFN=segments_1,generation=1}
[junit4] 2> 2970491 T8784 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2970504 T8809 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2970506 T8798 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2970496 T8783 oass.F
[...truncated too long message...]
3B]:0)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForNon403or404or503(AbstractFullDistribZkTestBase.java:1821)
[junit4] > at org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:331)
[junit4] > at org.apache.solr.cloud.CustomCollectionTest.doTest(CustomCollectionTest.java:131)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
[junit4] > at java.lang.Thread.run(Thread.java:662)
[junit4] 2> 3052255 T8736 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene46: {_route_=Lucene41(blocksize=128), range_facet_l=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=123), text=Lucene41(blocksize=128), _version_=PostingsFormat(name=Asserting), intDefault=PostingsFormat(name=Asserting), id=PostingsFormat(name=Direct), timestamp=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=123), a_t=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=123), range_facet_sl=PostingsFormat(name=Direct), range_facet_si=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=123), other_tl1=PostingsFormat(name=Asserting), multiDefault=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=123), a_si=PostingsFormat(name=Asserting)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=es_BO, timezone=Europe/Tirane
[junit4] 2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_45 (32-bit)/cpus=2,threads=1,free=95176512,total=223236096
[junit4] 2> NOTE: All tests run in this JVM: [TestXIncludeConfig, CacheHeaderTest, TestStressVersions, DateMathParserTest, TestDocBasedVersionConstraints, BlockCacheTest, TestSolrQueryParserResource, TestCoreDiscovery, TestNRTOpen, TestQueryTypes, TestRTGBase, IndexBasedSpellCheckerTest, ShowFileRequestHandlerTest, HdfsChaosMonkeySafeLeaderTest, DocValuesMultiTest, StatsComponentTest, TestOmitPositions, UUIDFieldTest, TestBlendedInfixSuggestions, TestFieldCollectionResource, MigrateRouteKeyTest, TermVectorComponentTest, URLClassifyProcessorTest, TestFaceting, SuggesterWFSTTest, TestFreeTextSuggestions, MoreLikeThisHandlerTest, TestFileDictionaryLookup, TestFieldTypeCollectionResource, SolrTestCaseJ4Test, ChangedSchemaMergeTest, CSVRequestHandlerTest, DistanceFunctionTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DirectUpdateHandlerOptimizeTest, TestNumberUtils, DefaultValueUpdateProcessorTest, CurrencyFieldXmlFileTest, TestStressRecovery, HardAutoCommitTest, LeaderElectionIntegrationTest, TestQueryUtils, SuggesterTest, TestTrie, TestSchemaNameResource, SearchHandlerTest, TestRecovery, TestUniqueKeyFieldResource, ExternalFileFieldSortTest, FileUtilsTest, TestRecoveryHdfs, TestInitQParser, DeleteReplicaTest, BlockDirectoryTest, HdfsBasicDistributedZk2Test, ScriptEngineTest, TestLMJelinekMercerSimilarityFactory, LeaderElectionTest, TestJmxIntegration, AnalysisAfterCoreReloadTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, HdfsSyncSliceTest, AddBlockUpdateTest, BasicDistributedZkTest, ParsingFieldUpdateProcessorsTest, TestSolrXmlPersistence, SolrIndexConfigTest, TestLMDirichletSimilarityFactory, InfoHandlerTest, TestSearchPerf, ZkControllerTest, TestFastWriter, AutoCommitTest, DistributedTermsComponentTest, PluginInfoTest, ConvertedLegacyTest, TestConfig, SampleTest, DocValuesMissingTest, BadIndexSchemaTest, FieldMutatingUpdateProcessorTest, TermVectorComponentDistributedTest, TestNoOpRegenerator, TestSurroundQueryParser, FullSolrCloudDistribCmdsTest, TestDefaultSearchFieldResource, OutputWriterTest, JsonLoaderTest, TestBinaryField, TestCopyFieldCollectionResource, TestElisionMultitermQuery, TestAnalyzeInfixSuggestions, ReturnFieldsTest, TestHighFrequencyDictionaryFactory, UpdateParamsTest, TestSolrXMLSerializer, SynonymTokenizerTest, TestSolrQueryParserDefaultOperatorResource, TestPHPSerializedResponseWriter, HdfsBasicDistributedZkTest, RegexBoostProcessorTest, TestCSVLoader, PrimitiveFieldTypeTest, TestReplicationHandler, DirectSolrSpellCheckerTest, TestSolrDeletionPolicy2, TermsComponentTest, TestAnalyzedSuggestions, TestSolr4Spatial, TestSuggestSpellingConverter, OverseerTest, LegacyHTMLStripCharFilterTest, TestInfoStreamLogging, AnalysisErrorHandlingTest, StressHdfsTest, SuggestComponentTest, SpellCheckComponentTest, TestLazyCores, TestValueSourceCache, RequiredFieldsTest, DocumentBuilderTest, HighlighterConfigTest, TestManagedSchema, AssignTest, TestJmxMonitoredMap, DebugComponentTest, SuggesterTSTTest, TestQuerySenderNoQuery, TestFunctionQuery, SOLR749Test, TestDistributedGrouping, TestManagedSchemaFieldResource, TestPhraseSuggestions, PolyFieldTest, TestReload, ShardSplitTest, TestDistributedMissingSort, SpellCheckCollatorTest, SolrCoreTest, TestFoldingMultitermQuery, SolrCmdDistributorTest, CoreContainerCoreInitFailuresTest, SpellPossibilityIteratorTest, BasicFunctionalityTest, WordBreakSolrSpellCheckerTest, HdfsCollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, StatelessScriptUpdateProcessorFactoryTest, TestNonDefinedSimilarityFactory, SortByFunctionTest, BasicZkTest, SolrPluginUtilsTest, TestSolrJ, TestStressReorder, TestCursorMarkWithoutUniqueKey, TestDistributedSearch, ZkSolrClientTest, TestPostingsSolrHighlighter, SignatureUpdateProcessorFactoryTest, TestDynamicFieldResource, ResourceLoaderTest, TestSystemIdResolver, OverseerRolesTest, TestShardHandlerFactory, RecoveryZkTest, BadComponentTest, TestCodecSupport, NotRequiredUniqueKeyTest, PreAnalyzedUpdateProcessorTest, SliceStateUpdateTest, TestReloadAndDeleteDocs, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, SyncSliceTest, ShardRoutingTest, ClusterStateUpdateTest, TestRandomFaceting, TestZkChroot, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestHashPartitioner, DistributedSpellCheckComponentTest, TestRealTimeGet, TestJoin, TestMultiCoreConfBootstrap, TestRangeQuery, TestCoreContainer, SimpleFacetsTest, TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, TestSort, TestFiltering, DirectUpdateHandlerTest, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, SimplePostToolTest, TestExtendedDismaxParser, SuggesterFSTTest, CoreAdminHandlerTest, SolrRequestParserTest, DocValuesTest, TestStressLucene, SpatialFilterTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, TestRemoteStreaming, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, FileBasedSpellCheckerTest, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, TestComponentsName, TestBinaryResponseWriter, TestLFUCache, TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, ResponseLogComponentTest, TestMergePolicyConfig, MultiTermTest, TestDocSet, NumericFieldsTest, MinimalSchemaTest, TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, DirectSolrConnectionTest, TestLuceneMatchVersion, TestCharFilters, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, CircularListTest, CursorPagingTest, TestHighlightDedupGrouping, TestCollationKeyRangeQueries, AliasIntegrationTest, ConnectionManagerTest, CustomCollectionTest]
[junit4] Completed in 100.47s, 1 test, 1 failure <<< FAILURES!
[...truncated 149 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:459: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:439: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1308: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:941: There were test failures: 362 suites, 1562 tests, 1 failure, 42 ignored (14 assumptions)
Total time: 85 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.6.0_45 -client -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure