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/04/16 23:11:55 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0_05) - Build #
3877 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3877/
Java: 64bit/jdk1.8.0_05 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 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([B722E5568D2BD554]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:419)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:179)
at sun.reflect.GeneratedMethodAccessor27.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:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
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 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:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11359 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001
[junit4] 2> 2558336 T9745 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 2558338 T9745 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 2558338 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2558343 T9745 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 2558345 T9745 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2558347 T9745 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2558422 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558430 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2558430 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2558435 T9745 oass.IndexSchema.readSchema [null] Schema name=tiny
[junit4] 2> 2558442 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558442 T9745 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2558442 T9745 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr
[junit4] 2> 2558442 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\'
[junit4] 2> 2558488 T9745 oasc.CoreContainer.<init> New CoreContainer 1117524371
[junit4] 2> 2558488 T9745 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\]
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2558489 T9745 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2558489 T9745 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2558493 T9745 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2558493 T9745 oasc.CoreContainer.load Host Name:
[junit4] 2> 2558498 T9746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1/'
[junit4] 2> 2558499 T9746 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 2558499 T9746 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2558499 T9746 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2558533 T9746 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558541 T9746 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2558541 T9746 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2558545 T9746 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 2558547 T9746 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558547 T9746 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1/
[junit4] 2> 2558547 T9746 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558549 T9746 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1/, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\
[junit4] 2> 2558549 T9746 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2558550 T9746 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\
[junit4] 2> 2558550 T9746 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\index/
[junit4] 2> 2558550 T9746 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 2558550 T9746 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\index
[junit4] 2> 2558551 T9746 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=29.2509765625, floorSegmentMB=0.91015625, forceMergeDeletesPctAllowed=3.2163579570338827, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26222362338105143
[junit4] 2> 2558551 T9746 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16e58e80 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e7bc259),segFN=segments_1,generation=1}
[junit4] 2> 2558551 T9746 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558553 T9746 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2558553 T9746 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2558553 T9746 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2558554 T9746 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2558558 T9746 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2558562 T9746 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2558562 T9746 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2558562 T9746 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=46.4970703125, floorSegmentMB=1.0439453125, forceMergeDeletesPctAllowed=11.572761094409024, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1852943981811163
[junit4] 2> 2558562 T9746 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16e58e80 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e7bc259),segFN=segments_1,generation=1}
[junit4] 2> 2558562 T9746 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558563 T9746 oass.SolrIndexSearcher.<init> Opening Searcher@52971bd5[collection1] main
[junit4] 2> 2558563 T9746 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf
[junit4] 2> 2558563 T9746 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1/conf\}
[junit4] 2> 2558563 T9746 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf
[junit4] 2> 2558564 T9746 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2558564 T9746 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2558564 T9746 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2558566 T9746 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2558567 T9747 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52971bd5[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2558568 T9745 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 2558573 T9745 oas.SolrTestCaseJ4.setUp ###Starting testCachingLimit
[junit4] 2> 2558639 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\'
[junit4] 2> 2558666 T9745 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\solr.xml
[junit4] 2> 2558678 T9745 oasc.CoreContainer.<init> New CoreContainer 365746806
[junit4] 2> 2558678 T9745 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\]
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2558679 T9745 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2558679 T9745 oasc.SolrCores.allocateLazyCores Allocating transient cache for 4 transient cores
[junit4] 2> 2558679 T9745 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2558679 T9745 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2558679 T9745 oasc.CoreContainer.load Host Name: null
[junit4] 2> 2558697 T9749 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\'
[junit4] 2> 2558718 T9749 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558724 T9749 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2558724 T9749 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2558728 T9749 oass.IndexSchema.readSchema [collection1] Schema name=tiny
[junit4] 2> 2558731 T9749 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558731 T9749 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\
[junit4] 2> 2558732 T9749 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558733 T9749 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\
[junit4] 2> 2558733 T9749 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2558734 T9749 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\
[junit4] 2> 2558734 T9749 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\index/
[junit4] 2> 2558734 T9749 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2558734 T9749 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\index
[junit4] 2> 2558735 T9749 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=29.2509765625, floorSegmentMB=0.91015625, forceMergeDeletesPctAllowed=3.2163579570338827, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26222362338105143
[junit4] 2> 2558735 T9749 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6fc8da17 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29ed1f95),segFN=segments_1,generation=1}
[junit4] 2> 2558735 T9749 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558736 T9749 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2558736 T9749 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2558737 T9749 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2558737 T9749 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2558742 T9749 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2558743 T9749 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2558743 T9749 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2558743 T9749 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=46.4970703125, floorSegmentMB=1.0439453125, forceMergeDeletesPctAllowed=11.572761094409024, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1852943981811163
[junit4] 2> 2558743 T9749 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6fc8da17 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29ed1f95),segFN=segments_1,generation=1}
[junit4] 2> 2558743 T9749 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558744 T9749 oass.SolrIndexSearcher.<init> Opening Searcher@25997a1d[collection1] main
[junit4] 2> 2558744 T9749 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\conf
[junit4] 2> 2558744 T9749 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\conf\}
[junit4] 2> 2558744 T9749 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\conf
[junit4] 2> 2558747 T9749 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2558747 T9749 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2558747 T9749 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2558748 T9749 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2558748 T9751 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\'
[junit4] 2> 2558767 T9751 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558773 T9751 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2558775 T9751 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2558778 T9751 oass.IndexSchema.readSchema [collectionLazy5] Schema name=tiny
[junit4] 2> 2558780 T9751 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558780 T9751 oasc.CoreContainer.create Creating SolrCore 'collectionLazy5' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\
[junit4] 2> 2558780 T9751 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558781 T9751 oasc.SolrCore.<init> [collectionLazy5] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\
[junit4] 2> 2558781 T9751 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy5
[junit4] 2> 2558782 T9751 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\
[junit4] 2> 2558782 T9751 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\index/
[junit4] 2> 2558782 T9751 oasc.SolrCore.initIndex WARN [collectionLazy5] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\index' doesn't exist. Creating new index...
[junit4] 2> 2558782 T9751 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\index
[junit4] 2> 2558783 T9751 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=29.2509765625, floorSegmentMB=0.91015625, forceMergeDeletesPctAllowed=3.2163579570338827, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26222362338105143
[junit4] 2> 2558783 T9751 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@427ae3e3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2bfa646f),segFN=segments_1,generation=1}
[junit4] 2> 2558783 T9751 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558784 T9751 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2558784 T9751 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2558785 T9751 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2558785 T9751 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2558786 T9751 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2558787 T9751 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2558787 T9751 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2558787 T9751 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=46.4970703125, floorSegmentMB=1.0439453125, forceMergeDeletesPctAllowed=11.572761094409024, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1852943981811163
[junit4] 2> 2558787 T9751 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@427ae3e3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2bfa646f),segFN=segments_1,generation=1}
[junit4] 2> 2558787 T9751 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558788 T9751 oass.SolrIndexSearcher.<init> Opening Searcher@43a83188[collectionLazy5] main
[junit4] 2> 2558788 T9751 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\conf
[junit4] 2> 2558788 T9751 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\conf\}
[junit4] 2> 2558788 T9751 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\conf
[junit4] 2> 2558790 T9751 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2558790 T9751 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2558790 T9751 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2558792 T9751 oasc.CoreContainer.registerCore registering core: collectionLazy5
[junit4] 2> 2558800 T9753 oasc.SolrCore.registerSearcher [collectionLazy5] Registered new searcher Searcher@43a83188[collectionLazy5] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2558801 T9752 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25997a1d[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2558801 T9750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\'
[junit4] 2> 2558823 T9750 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558828 T9750 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2558828 T9750 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2558832 T9750 oass.IndexSchema.readSchema [collectionLazy2] Schema name=tiny
[junit4] 2> 2558834 T9750 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558834 T9750 oasc.CoreContainer.create Creating SolrCore 'collectionLazy2' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\
[junit4] 2> 2558834 T9750 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558835 T9750 oasc.SolrCore.<init> [collectionLazy2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\data\
[junit4] 2> 2558835 T9750 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy2
[junit4] 2> 2558841 T9750 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\data\
[junit4] 2> 2558841 T9750 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\data\index/
[junit4] 2> 2558841 T9750 oasc.SolrCore.initIndex WARN [collectionLazy2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\data\index' doesn't exist. Creating new index...
[junit4] 2> 2558842 T9750 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\data\index
[junit4] 2> 2558842 T9750 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=29.2509765625, floorSegmentMB=0.91015625, forceMergeDeletesPctAllowed=3.2163579570338827, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26222362338105143
[junit4] 2> 2558842 T9750 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f3feff4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8b913b9),segFN=segments_1,generation=1}
[junit4] 2> 2558842 T9750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558843 T9750 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2558843 T9750 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2558844 T9750 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2558844 T9750 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2558845 T9750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2558846 T9750 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2558846 T9750 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2558846 T9750 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=46.4970703125, floorSegmentMB=1.0439453125, forceMergeDeletesPctAllowed=11.572761094409024, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1852943981811163
[junit4] 2> 2558846 T9750 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f3feff4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8b913b9),segFN=segments_1,generation=1}
[junit4] 2> 2558846 T9750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558847 T9750 oass.SolrIndexSearcher.<init> Opening Searcher@1ff233ce[collectionLazy2] main
[junit4] 2> 2558847 T9750 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\conf
[junit4] 2> 2558847 T9750 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\conf\}
[junit4] 2> 2558847 T9750 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection2\conf
[junit4] 2> 2558849 T9750 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2558849 T9750 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2558849 T9750 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2558851 T9750 oasc.SolrCores.putTransientCore Opening transient core collectionLazy2
[junit4] 2> 2558851 T9750 oasc.CoreContainer.registerCore registering core: collectionLazy2
[junit4] 2> 2558852 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\'
[junit4] 2> 2558874 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558878 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2558880 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2558883 T9745 oass.IndexSchema.readSchema [collectionLazy3] Schema name=tiny
[junit4] 2> 2558886 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558886 T9745 oasc.CoreContainer.create Creating SolrCore 'collectionLazy3' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\
[junit4] 2> 2558886 T9745 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558887 T9745 oasc.SolrCore.<init> [collectionLazy3] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\
[junit4] 2> 2558887 T9745 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy3
[junit4] 2> 2558888 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\
[junit4] 2> 2558888 T9745 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\index/
[junit4] 2> 2558888 T9745 oasc.SolrCore.initIndex WARN [collectionLazy3] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\index' doesn't exist. Creating new index...
[junit4] 2> 2558889 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\index
[junit4] 2> 2558890 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=85.2880859375, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=11.338651895646361, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1787535360183295
[junit4] 2> 2558890 T9745 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5bca8ab9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c82d86),segFN=segments_1,generation=1}
[junit4] 2> 2558890 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558891 T9745 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2558891 T9745 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2558892 T9745 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2558893 T9745 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2558894 T9745 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2558894 T9745 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2558894 T9745 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2558895 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=68.2197265625, floorSegmentMB=1.146484375, forceMergeDeletesPctAllowed=19.529117593788374, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2558895 T9745 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5bca8ab9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c82d86),segFN=segments_1,generation=1}
[junit4] 2> 2558895 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558896 T9745 oass.SolrIndexSearcher.<init> Opening Searcher@1251ba8f[collectionLazy3] main
[junit4] 2> 2558896 T9745 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\conf
[junit4] 2> 2558896 T9745 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\conf\}
[junit4] 2> 2558896 T9745 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\conf
[junit4] 2> 2558899 T9745 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2558899 T9745 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2558899 T9745 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2558900 T9745 oasc.SolrCores.putTransientCore Opening transient core collectionLazy3
[junit4] 2> 2558900 T9745 oasc.CoreContainer.registerCore registering core: collectionLazy3
[junit4] 2> 2558900 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\'
[junit4] 2> 2558930 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558938 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2558938 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2558941 T9745 oass.IndexSchema.readSchema [collectionLazy4] Schema name=tiny
[junit4] 2> 2558944 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558944 T9745 oasc.CoreContainer.create Creating SolrCore 'collectionLazy4' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\
[junit4] 2> 2558944 T9745 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558946 T9745 oasc.SolrCore.<init> [collectionLazy4] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\
[junit4] 2> 2558947 T9745 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy4
[junit4] 2> 2558947 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\
[junit4] 2> 2558947 T9745 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\index/
[junit4] 2> 2558947 T9745 oasc.SolrCore.initIndex WARN [collectionLazy4] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\index' doesn't exist. Creating new index...
[junit4] 2> 2558948 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\index
[junit4] 2> 2558948 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1786692915, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2558949 T9745 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@102c4163 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39bb9014),segFN=segments_1,generation=1}
[junit4] 2> 2558949 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558951 T9745 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2558951 T9745 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2558951 T9745 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2558952 T9745 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2558953 T9745 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2558953 T9745 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2558953 T9745 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2558954 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=6.5390625, floorSegmentMB=0.9658203125, forceMergeDeletesPctAllowed=15.091501836831423, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5964774350567227
[junit4] 2> 2558954 T9745 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@102c4163 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39bb9014),segFN=segments_1,generation=1}
[junit4] 2> 2558954 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2558955 T9745 oass.SolrIndexSearcher.<init> Opening Searcher@64754174[collectionLazy4] main
[junit4] 2> 2558955 T9745 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\conf
[junit4] 2> 2558955 T9745 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\conf\}
[junit4] 2> 2558955 T9745 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\conf
[junit4] 2> 2558957 T9745 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2558957 T9745 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2558957 T9745 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2558959 T9745 oasc.CoreContainer.registerCore registering core: collectionLazy4
[junit4] 2> 2558959 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\'
[junit4] 2> 2558981 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2558987 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2558989 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2558992 T9745 oass.IndexSchema.readSchema [collectionLazy6] Schema name=tiny
[junit4] 2> 2558995 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2558995 T9745 oasc.CoreContainer.create Creating SolrCore 'collectionLazy6' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\
[junit4] 2> 2558995 T9745 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2558997 T9745 oasc.SolrCore.<init> [collectionLazy6] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\
[junit4] 2> 2558997 T9745 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy6
[junit4] 2> 2558998 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\
[junit4] 2> 2558998 T9745 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\index/
[junit4] 2> 2558998 T9745 oasc.SolrCore.initIndex WARN [collectionLazy6] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\index' doesn't exist. Creating new index...
[junit4] 2> 2558999 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\index
[junit4] 2> 2558999 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=4.1142578125, floorSegmentMB=1.96484375, forceMergeDeletesPctAllowed=10.587442226947465, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19303672696383087
[junit4] 2> 2559000 T9745 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@53e6660 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@746d8add),segFN=segments_1,generation=1}
[junit4] 2> 2559000 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559001 T9745 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2559001 T9745 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2559002 T9745 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2559002 T9745 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2559003 T9745 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2559003 T9745 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2559003 T9745 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2559004 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=68.408203125, floorSegmentMB=1.2470703125, forceMergeDeletesPctAllowed=1.8161172031669581, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2559004 T9745 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@53e6660 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@746d8add),segFN=segments_1,generation=1}
[junit4] 2> 2559004 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559005 T9745 oass.SolrIndexSearcher.<init> Opening Searcher@2b807707[collectionLazy6] main
[junit4] 2> 2559005 T9745 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\conf
[junit4] 2> 2559005 T9745 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\conf\}
[junit4] 2> 2559005 T9745 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\conf
[junit4] 2> 2559008 T9745 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2559008 T9745 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2559008 T9745 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2559009 T9745 oasc.SolrCores.putTransientCore Opening transient core collectionLazy6
[junit4] 2> 2559009 T9745 oasc.CoreContainer.registerCore registering core: collectionLazy6
[junit4] 2> 2559009 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\'
[junit4] 2> 2559044 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2559048 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2559050 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2559052 T9745 oass.IndexSchema.readSchema [collectionLazy7] Schema name=tiny
[junit4] 2> 2559055 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2559055 T9745 oasc.CoreContainer.create Creating SolrCore 'collectionLazy7' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\
[junit4] 2> 2559055 T9745 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2559057 T9745 oasc.SolrCore.<init> [collectionLazy7] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\data\
[junit4] 2> 2559057 T9745 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy7
[junit4] 2> 2559058 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\data\
[junit4] 2> 2559058 T9745 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\data\index/
[junit4] 2> 2559058 T9745 oasc.SolrCore.initIndex WARN [collectionLazy7] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\data\index' doesn't exist. Creating new index...
[junit4] 2> 2559059 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\data\index
[junit4] 2> 2559060 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=2.2275390625, floorSegmentMB=1.51171875, forceMergeDeletesPctAllowed=1.5234334622688772, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2559060 T9745 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71a567cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@179cb5ec),segFN=segments_1,generation=1}
[junit4] 2> 2559060 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559061 T9745 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2559061 T9745 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2559062 T9745 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2559062 T9745 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2559064 T9745 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2559065 T9745 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2559065 T9745 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2559065 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=6.5078125, floorSegmentMB=1.982421875, forceMergeDeletesPctAllowed=19.56407529595363, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2559066 T9756 oasc.SolrCore.registerSearcher [collectionLazy3] Registered new searcher Searcher@1251ba8f[collectionLazy3] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559066 T9754 oasc.SolrCore.registerSearcher [collectionLazy2] Registered new searcher Searcher@1ff233ce[collectionLazy2] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559066 T9760 oasc.SolrCore.registerSearcher [collectionLazy6] Registered new searcher Searcher@2b807707[collectionLazy6] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559066 T9758 oasc.SolrCore.registerSearcher [collectionLazy4] Registered new searcher Searcher@64754174[collectionLazy4] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559116 T9745 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71a567cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@179cb5ec),segFN=segments_1,generation=1}
[junit4] 2> 2559116 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559117 T9745 oass.SolrIndexSearcher.<init> Opening Searcher@21fb5970[collectionLazy7] main
[junit4] 2> 2559117 T9745 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\conf
[junit4] 2> 2559117 T9745 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\conf\}
[junit4] 2> 2559117 T9745 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\conf
[junit4] 2> 2559118 T9745 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2559119 T9745 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2559119 T9745 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2559120 T9745 oasc.SolrCores.putTransientCore Opening transient core collectionLazy7
[junit4] 2> 2559120 T9745 oasc.CoreContainer.registerCore registering core: collectionLazy7
[junit4] 2> 2559120 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\'
[junit4] 2> 2559142 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2559149 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2559149 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2559152 T9745 oass.IndexSchema.readSchema [collectionLazy8] Schema name=tiny
[junit4] 2> 2559154 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2559154 T9745 oasc.CoreContainer.create Creating SolrCore 'collectionLazy8' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\
[junit4] 2> 2559154 T9745 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2559156 T9745 oasc.SolrCore.<init> [collectionLazy8] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\data\
[junit4] 2> 2559156 T9745 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy8
[junit4] 2> 2559157 T9762 oasc.SolrCore.registerSearcher [collectionLazy7] Registered new searcher Searcher@21fb5970[collectionLazy7] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559157 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\data\
[junit4] 2> 2559157 T9745 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\data\index/
[junit4] 2> 2559157 T9745 oasc.SolrCore.initIndex WARN [collectionLazy8] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\data\index' doesn't exist. Creating new index...
[junit4] 2> 2559158 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\data\index
[junit4] 2> 2559159 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=23.4443359375, floorSegmentMB=0.8994140625, forceMergeDeletesPctAllowed=24.793641932389068, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6435619667688603
[junit4] 2> 2559159 T9745 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3dd8145f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eb995ff),segFN=segments_1,generation=1}
[junit4] 2> 2559159 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559160 T9745 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2559160 T9745 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2559161 T9745 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2559162 T9745 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2559163 T9745 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2559163 T9745 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2559163 T9745 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2559164 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17806335862862221]
[junit4] 2> 2559164 T9745 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3dd8145f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eb995ff),segFN=segments_1,generation=1}
[junit4] 2> 2559164 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559165 T9745 oass.SolrIndexSearcher.<init> Opening Searcher@4d158be8[collectionLazy8] main
[junit4] 2> 2559166 T9745 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\conf
[junit4] 2> 2559166 T9745 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\conf\}
[junit4] 2> 2559166 T9745 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection8\conf
[junit4] 2> 2559167 T9745 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2559167 T9745 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2559167 T9745 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2559169 T9745 oasc.SolrCores.putTransientCore Opening transient core collectionLazy8
[junit4] 2> 2559170 T9745 oasc.SolrCores$1.removeEldestEntry Closing transient core [collectionLazy3]
[junit4] 2> 2559170 T9745 oasc.CoreContainer.registerCore registering core: collectionLazy8
[junit4] 2> 2559170 T9745 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\'
[junit4] 2> 2559215 T9745 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 2559218 T9745 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2559218 T9745 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2559224 T9745 oass.IndexSchema.readSchema [collectionLazy9] Schema name=tiny
[junit4] 2> 2559226 T9745 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2559228 T9745 oasc.CoreContainer.create Creating SolrCore 'collectionLazy9' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\
[junit4] 2> 2559228 T9745 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2559229 T9745 oasc.SolrCore.<init> [collectionLazy9] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\data\
[junit4] 2> 2559229 T9745 oasc.SolrCore.<init> JMX monitoring not detected for core: collectionLazy9
[junit4] 2> 2559229 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\data\
[junit4] 2> 2559229 T9745 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\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\data\index/
[junit4] 2> 2559229 T9745 oasc.SolrCore.initIndex WARN [collectionLazy9] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\data\index' doesn't exist. Creating new index...
[junit4] 2> 2559230 T9745 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\data\index
[junit4] 2> 2559231 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2559232 T9745 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1809d688 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61014524),segFN=segments_1,generation=1}
[junit4] 2> 2559232 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559233 T9745 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2559233 T9745 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2559234 T9745 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2559234 T9745 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2559235 T9745 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2559237 T9745 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2559237 T9745 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2559237 T9745 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=904094548, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2559238 T9745 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1809d688 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61014524),segFN=segments_1,generation=1}
[junit4] 2> 2559238 T9745 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2559238 T9745 oass.SolrIndexSearcher.<init> Opening Searcher@e7e801d[collectionLazy9] main
[junit4] 2> 2559239 T9745 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\conf
[junit4] 2> 2559239 T9745 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\conf\}
[junit4] 2> 2559239 T9745 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection9\conf
[junit4] 2> 2559240 T9745 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2559240 T9745 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2559240 T9745 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2559242 T9745 oasc.SolrCores.putTransientCore Opening transient core collectionLazy9
[junit4] 2> 2559242 T9745 oasc.SolrCores$1.removeEldestEntry Closing transient core [collectionLazy2]
[junit4] 2> 2559242 T9745 oasc.CoreContainer.registerCore registering core: collectionLazy9
[junit4] 2> 2559242 T9745 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=365746806
[junit4] 2> 2559243 T9764 oasc.SolrCore.registerSearcher [collectionLazy8] Registered new searcher Searcher@4d158be8[collectionLazy8] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559243 T9766 oasc.SolrCore.registerSearcher [collectionLazy9] Registered new searcher Searcher@e7e801d[collectionLazy9] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2559243 T9748 oasc.SolrCore.close [collectionLazy3] CLOSING SolrCore org.apache.solr.core.SolrCore@773a30f7
[junit4] 2> 2559243 T9748 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> 2559243 T9748 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2559243 T9748 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2559244 T9748 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2559245 T9745 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@194d1fc6
[junit4] 2> 2559245 T9745 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> 2559245 T9745 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2559245 T9745 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2559245 T9745 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2559246 T9745 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2559246 T9745 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2559246 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\index;done=false>>]
[junit4] 2> 2559246 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\index
[junit4] 2> 2559246 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\;done=false>>]
[junit4] 2> 2559246 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection1\data\
[junit4] 2> 2559246 T9745 oasc.SolrCore.close [collectionLazy5] CLOSING SolrCore org.apache.solr.core.SolrCore@106dd0bd
[junit4] 2> 2559246 T9745 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> 2559248 T9745 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2559248 T9745 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2559248 T9745 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2559249 T9745 oasc.SolrCore.closeSearcher [collectionLazy5] Closing main searcher on request.
[junit4] 2> 2559249 T9745 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2559249 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\;done=false>>]
[junit4] 2> 2559249 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\
[junit4] 2> 2559249 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\index;done=false>>]
[junit4] 2> 2559249 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection5\data\index
[junit4] 2> 2559249 T9745 oasc.SolrCore.close [collectionLazy4] CLOSING SolrCore org.apache.solr.core.SolrCore@6f80aa7c
[junit4] 2> 2559249 T9745 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> 2559249 T9745 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2559249 T9745 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2559249 T9745 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2559252 T9745 oasc.SolrCore.closeSearcher [collectionLazy4] Closing main searcher on request.
[junit4] 2> 2559252 T9745 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2559252 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\;done=false>>]
[junit4] 2> 2559252 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\
[junit4] 2> 2559252 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\index;done=false>>]
[junit4] 2> 2559252 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection4\data\index
[junit4] 2> 2559252 T9745 oasc.SolrCore.close [collectionLazy6] CLOSING SolrCore org.apache.solr.core.SolrCore@3ca80350
[junit4] 2> 2559252 T9745 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> 2559252 T9745 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2559252 T9745 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2559252 T9745 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2559253 T9748 oasc.SolrCore.closeSearcher [collectionLazy3] Closing main searcher on request.
[junit4] 2> 2559255 T9748 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2559255 T9748 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\;done=false>>]
[junit4] 2> 2559255 T9748 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\
[junit4] 2> 2559255 T9748 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\index;done=false>>]
[junit4] 2> 2559255 T9748 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection3\data\index
[junit4] 2> 2559256 T9745 oasc.SolrCore.closeSearcher [collectionLazy6] Closing main searcher on request.
[junit4] 2> 2559256 T9745 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2559256 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\;done=false>>]
[junit4] 2> 2559256 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\
[junit4] 2> 2559256 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\index;done=false>>]
[junit4] 2> 2559256 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection6\data\index
[junit4] 2> 2559256 T9745 oasc.SolrCore.close [collectionLazy7] CLOSING SolrCore org.apache.solr.core.SolrCore@6037c216
[junit4] 2> 2559256 T9745 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> 2559256 T9745 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2559256 T9745 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2559258 T9745 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2559259 T9745 oasc.SolrCore.closeSearcher [collectionLazy7] Closing main searcher on request.
[junit4] 2> 2559259 T9745 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2559259 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\tempDir-001\collection7\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.
[...truncated too long message...]
CoreContainer instance=1117524371
[junit4] 2> 2562546 T9745 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d52d8c3
[junit4] 2> 2562547 T9745 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> 2562547 T9745 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2562547 T9745 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2562547 T9745 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2562548 T9745 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2562548 T9745 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2562548 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\index;done=false>>]
[junit4] 2> 2562548 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\index
[junit4] 2> 2562548 T9745 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\;done=false>>]
[junit4] 2> 2562548 T9745 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.core.TestLazyCores-B722E5568D2BD554-001\init-core-data-001\
[junit4] 2> 2676912 T9745 oas.SolrTestCaseJ4.endTrackingSearchers ERROR ERROR: SolrIndexSearcher opens=59 closes=58
[junit4] 2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=fi, timezone=America/Resolute
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=177128568,total=453357568
[junit4] 2> NOTE: All tests run in this JVM: [SliceStateUpdateTest, OpenCloseCoreStressTest, TestAnalyzeInfixSuggestions, TestComplexPhraseQParserPlugin, TestCoreContainer, LegacyHTMLStripCharFilterTest, NumericFieldsTest, TestImplicitCoreProperties, TestSolrXmlPersistor, DocValuesMissingTest, TestDocumentBuilder, RegexBoostProcessorTest, TriLevelCompositeIdRoutingTest, SystemInfoHandlerTest, HdfsUnloadDistributedZkTest, DistributedSuggestComponentTest, CoreAdminRequestStatusTest, ChangedSchemaMergeTest, CoreMergeIndexesAdminHandlerTest, TestFieldSortValues, TestUtils, IndexSchemaRuntimeFieldTest, AlternateDirectoryTest, TestZkChroot, FullSolrCloudDistribCmdsTest, PrimitiveFieldTypeTest, TestNumberUtils, FastVectorHighlighterTest, HdfsDirectoryTest, PluginInfoTest, ZkCLITest, FieldAnalysisRequestHandlerTest, PolyFieldTest, SolrInfoMBeanTest, ShardSplitTest, DocValuesTest, TestAtomicUpdateErrorCases, RemoteQueryErrorTest, SortByFunctionTest, DocumentBuilderTest, LeaderElectionIntegrationTest, TestReplicationHandler, TestNonDefinedSimilarityFactory, TestDefaultSimilarityFactory, TestSolrIndexConfig, TestFiltering, BadCopyFieldTest, BasicFunctionalityTest, IndexSchemaTest, TestExpandComponent, TermsComponentTest, TestRestManager, SynonymTokenizerTest, StatsComponentTest, ScriptEngineTest, DirectUpdateHandlerOptimizeTest, ConnectionManagerTest, TestFoldingMultitermQuery, LoggingHandlerTest, TestMergePolicyConfig, DistributedDebugComponentTest, TestRandomFaceting, DeleteShardTest, CoreAdminHandlerTest, TestNonNRTOpen, ZkNodePropsTest, TestStressRecovery, DirectSolrConnectionTest, SolrRequestParserTest, TestCSVResponseWriter, CursorPagingTest, TestShortCircuitedRequests, ChaosMonkeyNothingIsSafeTest, SyncSliceTest, DistanceFunctionTest, TimeZoneUtilsTest, InfoHandlerTest, TestConfigSets, XsltUpdateRequestHandlerTest, DeleteReplicaTest, AsyncMigrateRouteKeyTest, TestPostingsSolrHighlighter, SpatialFilterTest, UpdateParamsTest, DisMaxRequestHandlerTest, NoCacheHeaderTest, SuggesterWFSTTest, TestReload, TestSchemaVersionResource, HdfsLockFactoryTest, TestSearcherReuse, SpellCheckComponentTest, BadIndexSchemaTest, OverseerTest, StressHdfsTest, TestRangeQuery, JsonLoaderTest, TestManagedSchemaFieldResource, AssignTest, TestAnalyzedSuggestions, TestLFUCache, TestFaceting, RecoveryZkTest, NotRequiredUniqueKeyTest, TestIndexSearcher, DistributedQueryComponentCustomSortTest, TestFieldResource, UUIDFieldTest, TestPhraseSuggestions, TestElisionMultitermQuery, TestBM25SimilarityFactory, BasicDistributedZk2Test, TestCursorMarkWithoutUniqueKey, HdfsSyncSliceTest, TestManagedStopFilterFactory, SuggesterTSTTest, TestBadConfig, CoreAdminCreateDiscoverTest, ClusterStateTest, ExternalFileFieldSortTest, TestHighFrequencyDictionaryFactory, TestStressVersions, SpellCheckCollatorTest, StatelessScriptUpdateProcessorFactoryTest, BlockDirectoryTest, QueryResultKeyTest, TestRecovery, DirectSolrSpellCheckerTest, HardAutoCommitTest, BlockCacheTest, CustomCollectionTest, TestBinaryField, TestCollationField, HdfsWriteToMultipleCollectionsTest, TestOmitPositions, TestShardHandlerFactory, TestCollapseQParserPlugin, TestInfoStreamLogging, HdfsBasicDistributedZkTest, QueryEqualityTest, PreAnalyzedFieldTest, TestCloudManagedSchemaAddField, SuggesterFSTTest, LukeRequestHandlerTest, TestCoreDiscovery, DistributedExpandComponentTest, TestRequestStatusCollectionAPI, SearchHandlerTest, TestValueSourceCache, IndexBasedSpellCheckerTest, HdfsRecoveryZkTest, TestWriterPerf, MultiTermTest, ClusterStateUpdateTest, TestSolrQueryParser, TestExceedMaxTermLength, TestQuerySenderNoQuery, TestUniqueKeyFieldResource, FileBasedSpellCheckerTest, CurrencyFieldXmlFileTest, DeleteInactiveReplicaTest, TestIBSimilarityFactory, TestRTGBase, TestLRUCache, TestSimpleQParserPlugin, TestSchemaSimilarityResource, TestFieldTypeCollectionResource, TestLMJelinekMercerSimilarityFactory, CachingDirectoryFactoryTest, TestUpdate, TestManagedSchema, TestIndexingPerformance, ConvertedLegacyTest, TestCSVLoader, TestCharFilters, TestDynamicFieldCollectionResource, TermVectorComponentDistributedTest, ResponseLogComponentTest, DateFieldTest, HighlighterConfigTest, MoreLikeThisHandlerTest, TestCustomSort, TestCollectionAPI, DistributedQueryElevationComponentTest, SolrIndexConfigTest, TestExtendedDismaxParser, TestInitQParser, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, ShardRoutingTest, BasicZkTest, LeaderElectionTest, ZkSolrClientTest, TestRandomDVFaceting, ShardRoutingCustomTest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestJoin, TestMultiCoreConfBootstrap, DistributedTermsComponentTest, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, TestSort, TestFunctionQuery, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=B722E5568D2BD554 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=America/Resolute -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([B722E5568D2BD554]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:419)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:179)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed in 119.38s, 8 tests, 1 failure <<< FAILURES!
[...truncated 507 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:45: 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:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1286: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:910: There were test failures: 387 suites, 1619 tests, 1 suite-level error, 42 ignored (21 assumptions)
Total time: 87 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0_05 -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 (64bit/jdk1.8.0_20-ea-b05) -
Build # 3878 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3878/
Java: 64bit/jdk1.8.0_20-ea-b05 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:51980, http://127.0.0.1:51968, http://127.0.0.1:51998, http://127.0.0.1:51989, http://127.0.0.1:52007]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:51980, http://127.0.0.1:51968, http://127.0.0.1:51998, http://127.0.0.1:51989, http://127.0.0.1:52007]
at __randomizedtesting.SeedInfo.seed([2DD8F37DB5447A6D:AC3E7D65C21B1A51]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:319)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:636)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1659)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1685)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:710)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
at sun.reflect.GeneratedMethodAccessor37.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:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 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:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at java.lang.Thread.run(Thread.java:744)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: org.apache.solr.common.SolrException
at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:353)
at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:309)
at org.apache.solr.handler.admin.CollectionsHandler.handleCreateAction(CollectionsHandler.java:474)
at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:147)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
at org.apache.solr.servlet.SolrDispatchFilter.handleAdminRequest(SolrDispatchFilter.java:726)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:267)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:134)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:744)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:554)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:340)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:301)
... 45 more
Build Log:
[...truncated 11197 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\init-core-data-001
[junit4] 2> 1758762 T6746 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1758763 T6746 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1758770 T6746 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1758770 T6746 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 1758773 T6747 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1758889 T6746 oasc.ZkTestServer.run start zk server on port:51955
[junit4] 2> 1758891 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1758900 T6753 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@27f3163e name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1758900 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1758900 T6746 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1758909 T6748 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1456df4b1e40000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 1758912 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1758916 T6755 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3bf34508 name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1758916 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1758916 T6746 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1758924 T6746 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1758929 T6746 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1758935 T6746 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1758942 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1758943 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1758955 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1758956 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1758963 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1758964 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1758970 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1758971 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1758977 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1758978 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1758983 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1758986 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1758991 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1758992 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1758999 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1759000 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1759011 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1759011 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1759021 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1759021 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1759029 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1759030 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> Using old style solr.xml
[junit4] 2> 1759037 T6748 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1456df4b1e40001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 1759039 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1759046 T6757 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47302833 name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1759047 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1759047 T6746 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1759054 T6748 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1456df4b1e40002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 1759057 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1759061 T6759 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a41a06d name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1759062 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1759065 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf2/solrconfig.xml
[junit4] 2> 1759066 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 1759077 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf2/schema.xml
[junit4] 2> 1759078 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 1759090 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1759090 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1759099 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf2/stopwords.txt
[junit4] 2> 1759100 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 1759107 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf2/protwords.txt
[junit4] 2> 1759108 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 1759114 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf2/currency.xml
[junit4] 2> 1759115 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 1759123 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
[junit4] 2> 1759123 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 1759132 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1759133 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1759139 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf2/old_synonyms.txt
[junit4] 2> 1759141 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 1759146 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf2/synonyms.txt
[junit4] 2> 1759146 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 1759153 T6746 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml to /configs/conf2/elevate.xml
[junit4] 2> 1759153 T6746 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 1761902 T6746 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1761912 T6746 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51968
[junit4] 2> 1761912 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1761913 T6746 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1761913 T6746 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-002
[junit4] 2> 1761913 T6746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-002\'
[junit4] 2> 1761971 T6746 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\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-002\solr.xml
[junit4] 2> 1762003 T6746 oasc.CoreContainer.<init> New CoreContainer 1178098789
[junit4] 2> 1762003 T6746 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-002\]
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1762006 T6746 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1762006 T6746 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1762006 T6746 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1762011 T6746 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1762011 T6746 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51955/solr
[junit4] 2> 1762011 T6746 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1762013 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1762018 T6770 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1955029a name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1762019 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1762026 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1762030 T6772 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63982b68 name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1762031 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1762035 T6746 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1762043 T6746 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1762050 T6746 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1762059 T6746 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1762069 T6746 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1762078 T6746 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1762084 T6746 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51968_
[junit4] 2> 1762086 T6746 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51968_
[junit4] 2> 1762093 T6746 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1762111 T6746 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1762122 T6746 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51968_
[junit4] 2> 1762122 T6746 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1762128 T6746 oasc.Overseer.start Overseer (id=91600265154920453-127.0.0.1:51968_-n_0000000000) starting
[junit4] 2> 1762137 T6746 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1762168 T6774 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1762168 T6746 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1762170 T6774 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes at 127.0.0.1:51968_
[junit4] 2> 1762176 T6746 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1762182 T6746 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1762189 T6773 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1762195 T6746 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1762195 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1762197 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1762200 T6777 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12404184 name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1762200 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1762206 T6746 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1762215 T6746 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1764715 T6746 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 1764717 T6746 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1764723 T6746 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51980
[junit4] 2> 1764723 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1764723 T6746 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1764725 T6746 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-003
[junit4] 2> 1764725 T6746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-003\'
[junit4] 2> 1764769 T6746 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\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-003\solr.xml
[junit4] 2> 1764782 T6746 oasc.CoreContainer.<init> New CoreContainer 1585530531
[junit4] 2> 1764785 T6746 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-003\]
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1764785 T6746 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1764787 T6746 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1764787 T6746 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1764787 T6746 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1764787 T6746 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51955/solr
[junit4] 2> 1764789 T6746 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1764792 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1764803 T6788 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6776dcb4 name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1764805 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1764808 T6748 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1456df4b1e40007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 1764810 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1764813 T6790 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@65f580b name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1764813 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1764836 T6746 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1765880 T6746 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51980_
[junit4] 2> 1765883 T6746 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51980_
[junit4] 2> 1765888 T6772 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1765888 T6790 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1765889 T6777 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1765897 T6746 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1765897 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1768284 T6746 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 1768285 T6746 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1768290 T6746 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51989
[junit4] 2> 1768290 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1768292 T6746 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1768292 T6746 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004
[junit4] 2> 1768292 T6746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\'
[junit4] 2> 1768326 T6746 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\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solr.xml
[junit4] 2> 1768351 T6746 oasc.CoreContainer.<init> New CoreContainer 1706366892
[junit4] 2> 1768353 T6746 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\]
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1768355 T6746 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1768358 T6746 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1768358 T6746 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1768358 T6746 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1768360 T6746 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51955/solr
[junit4] 2> 1768360 T6746 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1768362 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1768369 T6802 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@48edf1dd name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1768371 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1768374 T6748 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1456df4b1e40009, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 1768376 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1768379 T6804 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3373aa5a name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1768379 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1768397 T6746 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1769440 T6746 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51989_
[junit4] 2> 1769442 T6746 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51989_
[junit4] 2> 1769448 T6804 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1769449 T6772 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1769449 T6790 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1769450 T6777 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1769461 T6746 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1769462 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1771685 T6746 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 1771685 T6746 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1771690 T6746 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51998
[junit4] 2> 1771690 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1771690 T6746 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1771690 T6746 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005
[junit4] 2> 1771694 T6746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\'
[junit4] 2> 1771735 T6746 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solr.xml
[junit4] 2> 1771749 T6746 oasc.CoreContainer.<init> New CoreContainer 1950350990
[junit4] 2> 1771749 T6746 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\]
[junit4] 2> 1771751 T6746 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1771751 T6746 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1771751 T6746 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1771751 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1771751 T6746 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1771751 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1771753 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1771753 T6746 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1771753 T6746 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1771753 T6746 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1771753 T6746 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1771755 T6746 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1771755 T6746 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51955/solr
[junit4] 2> 1771755 T6746 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1771758 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1771768 T6816 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ecfbb2 name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1771770 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1771779 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1771783 T6818 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@66bbf242 name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1771784 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1771799 T6746 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1772841 T6746 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51998_
[junit4] 2> 1772848 T6746 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51998_
[junit4] 2> 1772864 T6804 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1772865 T6772 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1772866 T6790 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1772867 T6818 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1772869 T6777 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1772883 T6746 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1772883 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1775031 T6746 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 1775031 T6746 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1775036 T6746 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52007
[junit4] 2> 1775036 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1775037 T6746 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1775037 T6746 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006
[junit4] 2> 1775037 T6746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\'
[junit4] 2> 1775073 T6746 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solr.xml
[junit4] 2> 1775082 T6746 oasc.CoreContainer.<init> New CoreContainer 1426521535
[junit4] 2> 1775082 T6746 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\]
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1775085 T6746 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1775088 T6746 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1775088 T6746 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1775088 T6746 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1775088 T6746 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51955/solr
[junit4] 2> 1775088 T6746 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1775092 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1775111 T6830 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@536d0eb name:ZooKeeperConnection Watcher:127.0.0.1:51955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1775113 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1775120 T6746 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1775122 T6832 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16545446 name:ZooKeeperConnection Watcher:127.0.0.1:51955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1775122 T6746 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1775135 T6746 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1776072 T6746 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52007_
[junit4] 2> 1776076 T6746 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52007_
[junit4] 2> 1776082 T6804 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1776082 T6777 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1776082 T6818 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1776083 T6790 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1776083 T6832 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1776082 T6772 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1776095 T6746 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1776095 T6746 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1776126 T6782 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&collection.configName=conf1&router.field=myOwnField&name=solrj_collection&router.name=compositeId&action=CREATE&numShards=2&wt=javabin&version=2
[junit4] 2> 1776129 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776131 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776132 T6774 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":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField",
[junit4] 2> "router.name":"compositeId"}
[junit4] 2> 1776132 T6774 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField",
[junit4] 2> "router.name":"compositeId"}
[junit4] 2> 1776135 T6774 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection
[junit4] 2> 1776135 T6774 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 1776142 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776145 T6773 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1776145 T6773 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection
[junit4] 2> 1776145 T6773 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2]
[junit4] 2> 1776150 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776151 T6804 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> 1776152 T6772 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> 1776152 T6818 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> 1776152 T6832 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> 1776152 T6790 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> 1776153 T6777 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> 1776236 T6774 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 1776236 T6774 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:52007_
[junit4] 2> 1776239 T6774 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:51998_
[junit4] 2> 1776242 T6774 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:51989_
[junit4] 2> 1776245 T6824 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard1_replica1&action=CREATE&numShards=2&collection=solrj_collection&shard=shard1&wt=javabin&version=2
[junit4] 2> 1776246 T6811 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard1_replica2&action=CREATE&numShards=2&collection=solrj_collection&shard=shard1&wt=javabin&version=2
[junit4] 2> 1776247 T6774 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:51980_
[junit4] 2> 1776252 T6799 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard2_replica1&action=CREATE&numShards=2&collection=solrj_collection&shard=shard2&wt=javabin&version=2
[junit4] 2> 1776253 T6811 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down collection=solrj_collection
[junit4] 2> 1776252 T6824 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down collection=solrj_collection
[junit4] 2> 1776256 T6824 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1776257 T6783 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard2_replica2&action=CREATE&numShards=2&collection=solrj_collection&shard=shard2&wt=javabin&version=2
[junit4] 2> 1776257 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776258 T6799 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down collection=solrj_collection
[junit4] 2> 1776257 T6811 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1776261 T6799 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1776263 T6783 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down collection=solrj_collection
[junit4] 2> 1776263 T6773 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1776264 T6773 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51998",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51998_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1776265 T6783 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1776265 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776270 T6773 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:52007",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52007_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1776276 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776277 T6773 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51989",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51989_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1776281 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776283 T6773 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51980",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51980_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1776287 T6772 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1776391 T6804 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> 1776392 T6818 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> 1776393 T6772 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> 1776394 T6832 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> 1776394 T6790 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> 1776395 T6777 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> 1777189 T6824 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 1777189 T6824 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solr.xml
[junit4] 2> 1777189 T6824 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1777192 T6811 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 1777193 T6799 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 1777194 T6824 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1777194 T6799 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to 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\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solr.xml
[junit4] 2> 1777194 T6824 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1777195 T6799 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1777199 T6799 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1777199 T6824 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1777200 T6824 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\'
[junit4] 2> 1777200 T6811 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solr.xml
[junit4] 2> 1777201 T6811 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1777199 T6799 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1777200 T6783 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 1777207 T6783 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to 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\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-003\solr.xml
[junit4] 2> 1777207 T6783 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1777212 T6811 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1777214 T6811 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1777216 T6783 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1777216 T6783 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1777221 T6799 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1777221 T6799 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\'
[junit4] 2> 1777223 T6811 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1777223 T6811 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\'
[junit4] 2> 1777230 T6783 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1777234 T6783 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-003\solrj_collection_shard2_replica2\'
[junit4] 2> 1777265 T6824 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 1777286 T6824 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1777301 T6811 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 1777303 T6824 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1777308 T6824 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 1777312 T6811 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1777333 T6799 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 1777344 T6799 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1777350 T6811 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1777355 T6811 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 1777454 T6824 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777458 T6824 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777459 T6824 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1777459 T6799 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1777475 T6824 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1777477 T6824 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1777483 T6799 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 1777475 T6783 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_49
[junit4] 2> 1777531 T6783 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1777522 T6811 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777533 T6811 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777534 T6811 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1777546 T6811 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1777547 T6811 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1777554 T6783 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1777560 T6783 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 1777634 T6799 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777634 T6799 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777635 T6799 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1777638 T6799 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1777640 T6799 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1777673 T6824 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777680 T6824 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777687 T6811 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777691 T6824 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1777696 T6824 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1777696 T6824 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1777696 T6824 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1777699 T6811 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777703 T6799 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777704 T6824 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1777704 T6824 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1777704 T6824 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using configuration from collection solrj_collection
[junit4] 2> 1777704 T6824 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1777706 T6824 oasc.SolrCore.<init> [solrj_collection_shard1_replica1] Opening new SolrCore at .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\, dataDir=.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\data\
[junit4] 2> 1777706 T6824 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@22f2e350
[junit4] 2> 1777707 T6824 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\data\
[junit4] 2> 1777707 T6824 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\data\index/
[junit4] 2> 1777707 T6824 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica1] Solr index directory '.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1777708 T6811 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1777706 T6783 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777709 T6783 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1777710 T6783 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1777711 T6799 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777708 T6824 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-006\solrj_collection_shard1_replica1\data\index
[junit4] 2> 1777713 T6824 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=6.7509765625, floorSegmentMB=1.8984375, forceMergeDeletesPctAllowed=23.618826550092916, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8535492423072262
[junit4] 2> 1777714 T6824 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d0f475a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c41c725),segFN=segments_1,generation=1}
[junit4] 2> 1777714 T6824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1777715 T6783 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1777715 T6783 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1777718 T6824 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1777719 T6824 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1777719 T6824 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1777720 T6824 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1777720 T6824 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1777720 T6824 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1777721 T6824 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1777722 T6824 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1777722 T6824 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1777722 T6824 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1777723 T6824 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1777723 T6824 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1777723 T6824 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1777721 T6811 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1777725 T6811 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1777724 T6824 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1777723 T6799 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1777726 T6824 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1777726 T6824 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1777725 T6811 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1777730 T6799 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1777730 T6799 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1777730 T6799 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1777732 T6811 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1777732 T6811 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1777732 T6811 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using configuration from collection solrj_collection
[junit4] 2> 1777732 T6811 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1777734 T6811 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\, dataDir=.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\data\
[junit4] 2> 1777734 T6811 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@22f2e350
[junit4] 2> 1777735 T6824 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1777735 T6811 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\data\
[junit4] 2> 1777735 T6811 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\data\index/
[junit4] 2> 1777736 T6811 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica2] Solr index directory '.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 1777737 T6811 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-005\solrj_collection_shard1_replica2\data\index
[junit4] 2> 1777737 T6811 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=6.7509765625, floorSegmentMB=1.8984375, forceMergeDeletesPctAllowed=23.618826550092916, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8535492423072262
[junit4] 2> 1777739 T6824 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1777739 T6824 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1777740 T6824 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=54.2861328125, floorSegmentMB=1.0205078125, forceMergeDeletesPctAllowed=22.0824766121831, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1777742 T6799 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1777742 T6824 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d0f475a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c41c725),segFN=segments_1,generation=1}
[junit4] 2> 1777743 T6824 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1777743 T6824 oass.SolrIndexSearcher.<init> Opening Searcher@ee56497[solrj_collection_shard1_replica1] main
[junit4] 2> 1777743 T6824 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1777743 T6799 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1777745 T6799 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using configuration from collection solrj_collection
[junit4] 2> 1777745 T6799 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1777746 T6799 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\data\
[junit4] 2> 1777746 T6799 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@22f2e350
[junit4] 2> 1777748 T6799 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\data\
[junit4] 2> 1777748 T6799 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\data\index/
[junit4] 2> 1777748 T6799 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1777749 T6799 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001\tempDir-004\solrj_collection_shard2_replica1\data\index
[junit4] 2> 1777748 T6811 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7fd055c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f365411),segFN=segments_1,generation=1}
[junit4] 2> 1777751 T6811 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1777751 T6824 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1777752 T6824 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1777751 T6799 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=6.7509765625, floorSegmentMB=1.8984375, forceMergeDeletesPctAllowed=23.618826550092916, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8535492423072262
[junit4] 2> 1777754 T6799 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3ad9a53a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@68af646f),segFN=segments_1,generation=1}
[junit4] 2> 1777754 T6799 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1777755 T6811 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1777755 T6811 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1777756 T6811 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1777756 T6811 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1777756 T6811 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1777756 T6811 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1777756 T6811 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1777756 T6811 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1777757 T6811 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1777756 T6799 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1777758 T6824 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1777759 T6824 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1777759 T6824 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1777758 T6811 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1777758 T6799 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1777761 T6799 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1777760 T6811 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1777761 T6811 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1777762 T6811 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1777761 T6799 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1777761 T6824 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1777763 T6799 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1777762 T6811 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1777765 T6811 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1777764 T6799 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1777764 T6824 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1777767 T6824 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 1777766 T6799 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1777765 T6811 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1777767 T6799 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1777767 T6799 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1777768 T6799 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1777769 T6783 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1777767 T6824 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1777769 T6799 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1777770 T6799 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1777768 T6811 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1777772 T6799 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 177777
[...truncated too long message...]
lientCnxn.java:498)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:40)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:123)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:209)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:417)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:386)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:373)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:128)
[junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:34)
[junit4] 2> ... 8 more
[junit4] 2>
[junit4] 2> 1951775 T6818 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1951775 T6818 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 1951775 T6818 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1951775 T6818 oasc.LeaderElector$ElectionWatcher.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.CollectionsAPIDistributedZkTest-2DD8F37DB5447A6D-001
[junit4] 2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=fr_CA, timezone=PNT
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_20-ea (64-bit)/cpus=2,threads=1,free=122426464,total=260513792
[junit4] 2> NOTE: All tests run in this JVM: [TestCustomSort, CacheHeaderTest, TestQuerySenderNoQuery, XsltUpdateRequestHandlerTest, TestSearcherReuse, ResponseLogComponentTest, OpenExchangeRatesOrgProviderTest, TestCollapseQParserPlugin, TestPseudoReturnFields, TestDistributedMissingSort, DistributedSuggestComponentTest, TimeZoneUtilsTest, MBeansHandlerTest, TestMergePolicyConfig, TestSolrJ, TestSurroundQueryParser, SoftAutoCommitTest, SpellPossibilityIteratorTest, BadComponentTest, SyncSliceTest, TestUniqueKeyFieldResource, TestComplexPhraseQParserPlugin, TestArbitraryIndexDir, TestMaxScoreQueryParser, TestSchemaNameResource, TestFiltering, RequestHandlersTest, TestQueryTypes, HighlighterConfigTest, DeleteReplicaTest, TestInfoStreamLogging, CoreAdminRequestStatusTest, HdfsDirectoryTest, StatelessScriptUpdateProcessorFactoryTest, SuggesterWFSTTest, DirectSolrSpellCheckerTest, HdfsLockFactoryTest, InfoHandlerTest, TestNumberUtils, SolrPluginUtilsTest, ZkControllerTest, TestSuggestSpellingConverter, BlockDirectoryTest, CurrencyFieldXmlFileTest, TestUpdate, TestWriterPerf, TestValueSourceCache, TestDistribDocBasedVersion, SliceStateUpdateTest, TestLMJelinekMercerSimilarityFactory, TestReplicationHandler, TestBM25SimilarityFactory, UnloadDistributedZkTest, TestManagedResourceStorage, ParsingFieldUpdateProcessorsTest, TestSolrQueryParser, TestCharFilters, OverseerCollectionProcessorTest, TestAnalyzedSuggestions, SliceStateTest, TestUtils, CursorPagingTest, TestCollationKeyRangeQueries, HighlighterTest, SignatureUpdateProcessorFactoryTest, TestExpandComponent, OverseerStatusTest, EnumFieldTest, TestJmxMonitoredMap, TestComponentsName, TestManagedResource, MinimalSchemaTest, CopyFieldTest, CustomCollectionTest, RequiredFieldsTest, BadCopyFieldTest, TestNRTOpen, TestCoreContainer, SearchHandlerTest, DocExpirationUpdateProcessorFactoryTest, BJQParserTest, TestSolrCoreProperties, TestRequestStatusCollectionAPI, StressHdfsTest, HardAutoCommitTest, PreAnalyzedUpdateProcessorTest, CursorMarkTest, AddBlockUpdateTest, MoreLikeThisHandlerTest, TestFastOutputStream, TestLazyCores, RemoteQueryErrorTest, DistributedTermsComponentTest, TestFieldCollectionResource, TestStressRecovery, OverseerTest, PrimitiveFieldTypeTest, DirectUpdateHandlerOptimizeTest, DocumentBuilderTest, FieldAnalysisRequestHandlerTest, HdfsWriteToMultipleCollectionsTest, CircularListTest, TestLFUCache, BasicZkTest, SpellingQueryConverterTest, UpdateRequestProcessorFactoryTest, TermsComponentTest, TestRemoteStreaming, ZkSolrClientTest, TestDynamicFieldCollectionResource, BadIndexSchemaTest, TermVectorComponentTest, DistribCursorPagingTest, TestCodecSupport, PeerSyncTest, TestGroupingSearch, TestPostingsSolrHighlighter, TestShardHandlerFactory, TestHighlightDedupGrouping, CoreAdminCreateDiscoverTest, TestDynamicFieldResource, SpellCheckComponentTest, SOLR749Test, PrimUtilsTest, TestJoin, ZkCLITest, DirectUpdateHandlerTest, AnalysisErrorHandlingTest, SystemInfoHandlerTest, ClusterStateUpdateTest, MultiTermTest, TestSort, OpenCloseCoreStressTest, PathHierarchyTokenizerFactoryTest, AssignTest, UUIDFieldTest, HdfsCollectionsAPIDistributedZkTest, TermVectorComponentDistributedTest, TestIndexingPerformance, TestSolrQueryParserResource, TestFunctionQuery, FileBasedSpellCheckerTest, AnalysisAfterCoreReloadTest, TestReversedWildcardFilterFactory, TestReloadAndDeleteDocs, DebugComponentTest, TestSolrXmlPersistor, DeleteShardTest, RAMDirectoryFactoryTest, TestSerializedLuceneMatchVersion, SolrIndexConfigTest, TestCoreDiscovery, TestXIncludeConfig, TestFaceting, WordBreakSolrSpellCheckerTest, ResourceLoaderTest, TestCSVLoader, TestFieldTypeCollectionResource, CoreAdminHandlerTest, RecoveryZkTest, ChangedSchemaMergeTest, TestManagedSynonymFilterFactory, TestHashPartitioner, PingRequestHandlerTest, DOMUtilTest, BasicDistributedZk2Test, SSLMigrationTest, TestAnalyzeInfixSuggestions, SuggestComponentTest, TestDefaultSimilarityFactory, DocValuesTest, TestAtomicUpdateErrorCases, TestLRUCache, PreAnalyzedFieldTest, ScriptEngineTest, FullSolrCloudDistribCmdsTest, DistribDocExpirationUpdateProcessorTest, FileUtilsTest, NoCacheHeaderTest, TestStressReorder, TestNonNRTOpen, DateMathParserTest, CoreContainerCoreInitFailuresTest, NumericFieldsTest, TestOmitPositions, TestAddFieldRealTimeGet, ShardRoutingTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest]
[junit4] Completed in 193.99s, 1 test, 1 error <<< FAILURES!
[...truncated 591 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:45: 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:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1286: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:910: There were test failures: 387 suites, 1619 tests, 1 error, 42 ignored (21 assumptions)
Total time: 90 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0_20-ea-b05 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure