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 2013/10/11 12:33:34 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0-ea-b106) - Build
# 3271 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3271/
Java: 64bit/jdk1.8.0-ea-b106 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Fri Oct 11 00:06:17 GMT-10:00 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Fri Oct 11 00:06:17 GMT-10:00 2013
at __randomizedtesting.SeedInfo.seed([433CD2FEFAD0A906:9897D238FFF8C0B5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1514)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:818)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 10076 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 408736 T1235 oas.SolrTestCaseJ4.setUp ###Starting testNoWriter
[junit4] 2> 408755 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 408767 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50796
[junit4] 2> 408767 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 408767 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 408767 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master
[junit4] 2> 408767 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\'
[junit4] 2> 408795 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\solr.xml
[junit4] 2> 408875 T1235 oasc.CoreContainer.<init> New CoreContainer 1017622962
[junit4] 2> 408875 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\]
[junit4] 2> 408876 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 408876 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 408876 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 408877 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 408877 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 408877 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 408877 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 408877 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 408878 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 408878 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 408882 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 408883 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 408887 T1247 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1
[junit4] 2> 408887 T1247 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\'
[junit4] 2> 408913 T1247 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 408927 T1247 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 408929 T1247 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 408933 T1247 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 408946 T1247 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 408947 T1247 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 408947 T1247 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\
[junit4] 2> 408947 T1247 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 408948 T1247 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\
[junit4] 2> 408948 T1247 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\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index/
[junit4] 2> 408948 T1247 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 408948 T1247 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index
[junit4] 2> 408949 T1247 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=40.9833984375, floorSegmentMB=0.681640625, forceMergeDeletesPctAllowed=19.98043920634556, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 408949 T1247 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@24407d38 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@682a9adf),segFN=segments_1,generation=1}
[junit4] 2> 408949 T1247 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 408950 T1247 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 408952 T1247 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 408952 T1247 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 408953 T1247 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 408953 T1247 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 408953 T1247 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 408953 T1247 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 408958 T1247 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 408959 T1247 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 408959 T1247 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 408960 T1247 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=56.43359375, floorSegmentMB=1.0986328125, forceMergeDeletesPctAllowed=13.885452661399537, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10896531075579992
[junit4] 2> 408960 T1247 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@24407d38 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@682a9adf),segFN=segments_1,generation=1}
[junit4] 2> 408960 T1247 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 408961 T1247 oass.SolrIndexSearcher.<init> Opening Searcher@14789125 main
[junit4] 2> 408961 T1247 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 408962 T1247 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 408962 T1247 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 408963 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 408963 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 408964 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 408981 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 408987 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50799
[junit4] 2> 408990 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 408990 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 408992 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave
[junit4] 2> 408992 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\'
[junit4] 2> 409008 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\solr.xml
[junit4] 2> 409025 T1248 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14789125 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 409028 T1235 oasc.CoreContainer.<init> New CoreContainer 1725061992
[junit4] 2> 409028 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\]
[junit4] 2> 409030 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 409030 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 409030 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 409030 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 409032 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 409032 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 409032 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 409032 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 409032 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 409032 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 409034 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 409035 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 409041 T1261 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1
[junit4] 2> 409041 T1261 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\'
[junit4] 2> 409076 T1261 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 409084 T1261 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 409084 T1261 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 409095 T1261 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 409098 T1261 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 409098 T1261 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 409099 T1261 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\
[junit4] 2> 409099 T1261 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 409100 T1261 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\
[junit4] 2> 409100 T1261 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\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index/
[junit4] 2> 409100 T1261 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 409100 T1261 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index
[junit4] 2> 409100 T1261 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=40.9833984375, floorSegmentMB=0.681640625, forceMergeDeletesPctAllowed=19.98043920634556, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 409101 T1261 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@15d610e3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@81cba81),segFN=segments_1,generation=1}
[junit4] 2> 409101 T1261 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 409103 T1261 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 409103 T1261 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 409103 T1261 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 409103 T1261 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 409103 T1261 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 409104 T1261 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 409104 T1261 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 409106 T1261 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 409106 T1261 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 409107 T1261 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 409107 T1261 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=56.43359375, floorSegmentMB=1.0986328125, forceMergeDeletesPctAllowed=13.885452661399537, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10896531075579992
[junit4] 2> 409107 T1261 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@15d610e3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@81cba81),segFN=segments_1,generation=1}
[junit4] 2> 409107 T1261 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 409108 T1261 oass.SolrIndexSearcher.<init> Opening Searcher@1ee91f13 main
[junit4] 2> 409113 T1261 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 409113 T1261 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 409115 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 409115 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 409115 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 409123 T1262 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ee91f13 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 409132 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1725061992
[junit4] 2> 409134 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@abd3834
[junit4] 2> 409134 T1235 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> 409135 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 409135 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 409135 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 409137 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 409137 T1235 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 409137 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index;done=false>>]
[junit4] 2> 409137 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index
[junit4] 2> 409138 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\;done=false>>]
[junit4] 2> 409139 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\
[junit4] 2> 409139 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 409537 T1235 oejut.QueuedThreadPool.doStop WARN 7 threads could not be stopped
[junit4] 2> 409553 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1017622962
[junit4] 2> 409553 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3d095a9c
[junit4] 2> 409553 T1235 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> 409553 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 409554 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 409554 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 409557 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 409557 T1235 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 409557 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\;done=false>>]
[junit4] 2> 409557 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\
[junit4] 2> 409558 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index;done=false>>]
[junit4] 2> 409558 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index
[junit4] 2> 409558 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 409613 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 409617 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50799
[junit4] 2> 409617 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 409618 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 409618 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave
[junit4] 2> 409618 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\'
[junit4] 2> 409635 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\solr.xml
[junit4] 2> 409654 T1235 oasc.CoreContainer.<init> New CoreContainer 910009061
[junit4] 2> 409654 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\]
[junit4] 2> 409655 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 409655 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 409656 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 409656 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 409656 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 409656 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 409656 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 409657 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 409657 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 409657 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 409658 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 409659 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 409666 T1276 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1
[junit4] 2> 409666 T1276 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\'
[junit4] 2> 409707 T1276 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 409721 T1276 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 409722 T1276 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 409725 T1276 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 409728 T1276 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 409730 T1276 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 409730 T1276 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\
[junit4] 2> 409730 T1276 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 409731 T1276 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data
[junit4] 2> 409732 T1276 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\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index/
[junit4] 2> 409732 T1276 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 409733 T1276 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index
[junit4] 2> 409733 T1276 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 409738 T1276 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@303120d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 409738 T1276 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 409739 T1276 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 409739 T1276 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 409739 T1276 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 409739 T1276 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 409739 T1276 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 409741 T1276 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 409741 T1276 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 409748 T1276 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 409750 T1276 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 409750 T1276 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 409752 T1276 oass.SolrIndexSearcher.<init> Opening Searcher@7f6c85e6 main
[junit4] 2> 409753 T1276 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 409753 T1276 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 409753 T1277 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f6c85e6 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 409754 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 409754 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 409755 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 409759 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50796
[junit4] 2> 409759 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 409767 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 409767 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master
[junit4] 2> 409767 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\'
[junit4] 2> 409798 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\solr.xml
[junit4] 2> 409822 T1235 oasc.CoreContainer.<init> New CoreContainer 694759551
[junit4] 2> 409823 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\]
[junit4] 2> 409823 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 409823 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 409823 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 409824 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 409824 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 409824 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 409825 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 409825 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 409826 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 409826 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 409831 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 409831 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 409834 T1289 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1
[junit4] 2> 409835 T1289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\'
[junit4] 2> 409867 T1289 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 409884 T1289 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 409885 T1289 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 409889 T1289 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 409895 T1289 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 409895 T1289 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 409897 T1289 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\
[junit4] 2> 409897 T1289 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 409897 T1289 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data
[junit4] 2> 409898 T1289 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\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index/
[junit4] 2> 409898 T1289 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 409899 T1289 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index
[junit4] 2> 409900 T1289 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 409904 T1289 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3229482a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 409905 T1289 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 409906 T1289 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 409906 T1289 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 409907 T1289 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 409907 T1289 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 409907 T1289 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 409907 T1289 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 409907 T1289 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 409915 T1289 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 409917 T1289 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 409917 T1289 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 409920 T1289 oass.SolrIndexSearcher.<init> Opening Searcher@4878c4c7 main
[junit4] 2> 409920 T1289 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 409920 T1289 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 409920 T1289 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 409921 T1290 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4878c4c7 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 409921 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 409922 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C2023 name=collection1 org.apache.solr.core.SolrCore@6a458edb
[junit4] 2> 409929 T1270 C2023 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 409932 T1270 C2023 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@303120d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 409934 T1270 C2023 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 409935 T1270 C2023 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123456]} 0 10
[junit4] 2> 409941 T1275 C2023 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 409964 T1275 C2023 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@303120d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@303120d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 409964 T1275 C2023 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 409980 T1275 C2023 oass.SolrIndexSearcher.<init> Opening Searcher@76412160 main
[junit4] 2> 409981 T1275 C2023 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 409981 T1277 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@76412160 main{StandardDirectoryReader(segments_2:3 _0(4.6):c1)}
[junit4] 2> 409981 T1275 C2023 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 40
[junit4] 2> 409996 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=910009061
[junit4] 2> 409998 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6a458edb
[junit4] 2> 409999 T1235 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 410000 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 410000 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 410001 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 410003 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 410004 T1235 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 410004 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index;done=false>>]
[junit4] 2> 410004 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index
[junit4] 2> 410005 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data;done=false>>]
[junit4] 2> 410005 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data
[junit4] 2> 410005 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 410061 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 410065 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50799
[junit4] 2> 410067 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 410069 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 410069 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave
[junit4] 2> 410069 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\'
[junit4] 2> 410111 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\solr.xml
[junit4] 2> 410125 T1235 oasc.CoreContainer.<init> New CoreContainer 1875470216
[junit4] 2> 410125 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\]
[junit4] 2> 410126 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 410126 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 410126 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 410126 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 410126 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 410127 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 410127 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 410129 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 410129 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 410129 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 410134 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 410134 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 410145 T1303 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1
[junit4] 2> 410145 T1303 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\'
[junit4] 2> 410172 T1303 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 410186 T1303 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 410189 T1303 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 410192 T1303 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 410197 T1303 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 410197 T1303 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 410199 T1303 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\
[junit4] 2> 410199 T1303 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 410199 T1303 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data
[junit4] 2> 410200 T1303 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\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index/
[junit4] 2> 410201 T1303 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 410201 T1303 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 410202 T1303 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 410202 T1303 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 410202 T1303 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 410202 T1303 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 410202 T1303 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 410209 T1303 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 410213 T1303 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 410213 T1303 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 410213 T1303 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index
[junit4] 2> 410216 T1303 oass.SolrIndexSearcher.<init> Opening Searcher@21b524b3 main
[junit4] 2> 410217 T1303 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 410217 T1303 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 410218 T1304 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21b524b3 main{StandardDirectoryReader(segments_2:3 _0(4.6):c1)}
[junit4] 2> 410219 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 410219 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 410241 T1307 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 410241 T1307 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> ASYNC NEW_CORE C2024 name=collection1 org.apache.solr.core.SolrCore@58123db6
[junit4] 2> 410244 T1285 C2024 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C2025 name=collection1 org.apache.solr.core.SolrCore@3b025d6b
[junit4] 2> 410245 T1300 C2025 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:50796/solr/replication&command=fetchindex&wait=true} status=0 QTime=14
[junit4] 2> 410248 T1235 oas.SolrTestCaseJ4.tearDown ###Ending testNoWriter
[junit4] 2> 410262 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=694759551
[junit4] 2> 410263 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@58123db6
[junit4] 2> 410263 T1235 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> 410264 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 410264 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 410264 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 410265 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 410266 T1235 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 410266 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index;done=false>>]
[junit4] 2> 410266 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data\index
[junit4] 2> 410268 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data;done=false>>]
[junit4] 2> 410268 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944465\master\collection1\data
[junit4] 2> 410268 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 410411 T1235 oejut.QueuedThreadPool.doStop WARN 7 threads could not be stopped
[junit4] 2> 410425 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1875470216
[junit4] 2> 410425 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b025d6b
[junit4] 2> 411355 T1305 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:50796/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:50796/solr
[junit4] 2> 411358 T1235 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> 411359 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 411359 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 411359 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 411366 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 411367 T1235 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 411367 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data;done=false>>]
[junit4] 2> 411368 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data
[junit4] 2> 411368 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index;done=false>>]
[junit4] 2> 411368 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485944693\slave\collection1\data\index
[junit4] 2> 411368 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 411687 T1235 oejut.QueuedThreadPool.doStop WARN 6 threads could not be stopped
[junit4] 2> 411745 T1235 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 411764 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 411774 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50812
[junit4] 2> 411774 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 411775 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 411775 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master
[junit4] 2> 411775 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\'
[junit4] 2> 411807 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\solr.xml
[junit4] 2> 411830 T1235 oasc.CoreContainer.<init> New CoreContainer 2087080738
[junit4] 2> 411831 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\]
[junit4] 2> 411832 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 411832 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 411832 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 411832 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 411832 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 411832 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 411833 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 411833 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 411833 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 411834 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 411838 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 411838 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 411851 T1318 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1
[junit4] 2> 411851 T1318 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\'
[junit4] 2> 411884 T1318 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 411893 T1318 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 411894 T1318 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 411904 T1318 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 411909 T1318 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 411910 T1318 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 411911 T1318 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\
[junit4] 2> 411911 T1318 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 411911 T1318 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\
[junit4] 2> 411911 T1318 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\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\index/
[junit4] 2> 411911 T1318 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 411912 T1318 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\index
[junit4] 2> 411912 T1318 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=40.9833984375, floorSegmentMB=0.681640625, forceMergeDeletesPctAllowed=19.98043920634556, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 411912 T1318 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1a2227f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56bc62cc),segFN=segments_1,generation=1}
[junit4] 2> 411912 T1318 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 411914 T1318 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 411914 T1318 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 411914 T1318 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 411915 T1318 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 411915 T1318 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 411915 T1318 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 411915 T1318 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 411920 T1318 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 411923 T1318 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 411923 T1318 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 411926 T1318 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=56.43359375, floorSegmentMB=1.0986328125, forceMergeDeletesPctAllowed=13.885452661399537, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10896531075579992
[junit4] 2> 411928 T1318 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1a2227f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56bc62cc),segFN=segments_1,generation=1}
[junit4] 2> 411928 T1318 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 411928 T1318 oass.SolrIndexSearcher.<init> Opening Searcher@37fb40bf main
[junit4] 2> 411928 T1318 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 411928 T1318 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 411929 T1319 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37fb40bf main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 411929 T1318 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 411929 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 411929 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 411929 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 411953 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 411964 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50815
[junit4] 2> 411964 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 411964 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 411964 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave
[junit4] 2> 411964 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\'
[junit4] 2> 411981 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\solr.xml
[junit4] 2> 412000 T1235 oasc.CoreContainer.<init> New CoreContainer 389452178
[junit4] 2> 412001 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\]
[junit4] 2> 412003 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 412003 T1235 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 412003 T1235 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 412004 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 412004 T1235 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 412004 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 412004 T1235 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 412005 T1235 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 412005 T1235 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 412005 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 412006 T1235 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 412007 T1235 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 412010 T1330 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1
[junit4] 2> 412010 T1330 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\'
[junit4] 2> 412040 T1330 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 412055 T1330 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 412056 T1330 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 412059 T1330 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 412063 T1330 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 412063 T1330 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 412064 T1330 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\
[junit4] 2> 412064 T1330 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 412064 T1330 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\
[junit4] 2> 412064 T1330 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\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\index/
[junit4] 2> 412064 T1330 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 412065 T1330 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\index
[junit4] 2> 412066 T1330 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=40.9833984375, floorSegmentMB=0.681640625, forceMergeDeletesPctAllowed=19.98043920634556, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 412066 T1330 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@64ba23d2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f8bd021),segFN=segments_1,generation=1}
[junit4] 2> 412066 T1330 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 412073 T1330 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 412074 T1330 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 412074 T1330 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 412074 T1330 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 412074 T1330 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 412074 T1330 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 412075 T1330 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 412076 T1330 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 412078 T1330 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 412078 T1330 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 412078 T1330 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=56.43359375, floorSegmentMB=1.0986328125, forceMergeDeletesPctAllowed=13.885452661399537, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10896531075579992
[junit4] 2> 412079 T1330 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@64ba23d2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f8bd021),segFN=segments_1,generation=1}
[junit4] 2> 412079 T1330 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 412079 T1330 oass.SolrIndexSearcher.<init> Opening Searcher@15f80da6 main
[junit4] 2> 412080 T1330 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 412080 T1330 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 412080 T1331 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15f80da6 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 412081 T1235 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 412081 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 412083 T1235 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 412099 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=389452178
[junit4] 2> 412100 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@149605ca
[junit4] 2> 412101 T1235 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> 412101 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 412101 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 412101 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 412105 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 412106 T1235 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 412106 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\index;done=false>>]
[junit4] 2> 412107 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\index
[junit4] 2> 412107 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\;done=false>>]
[junit4] 2> 412107 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947659\slave\collection1\data\
[junit4] 2> 412108 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 413846 T1235 oejut.QueuedThreadPool.doStop WARN 6 threads could not be stopped
[junit4] 2> 413872 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2087080738
[junit4] 2> 413873 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@432cc631
[junit4] 2> 413873 T1235 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> 413873 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 413873 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 413874 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 413878 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 413878 T1235 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 413878 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\;done=false>>]
[junit4] 2> 413879 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\
[junit4] 2> 413879 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\index;done=false>>]
[junit4] 2> 413880 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\collection1\data\index
[junit4] 2> 413880 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 415728 T1235 oejut.QueuedThreadPool.doStop WARN 8 threads could not be stopped
[junit4] 2> 415730 T1235 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 415736 T1235 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50818
[junit4] 2> 415736 T1235 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 415737 T1235 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 415737 T1235 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master
[junit4] 2> 415737 T1235 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\'
[junit4] 2> 415755 T1235 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\solr.xml
[junit4] 2> 415777 T1235 oasc.CoreContainer.<init> New CoreContainer 2140703592
[junit4] 2> 415777 T1235 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381485947473\master\]
[junit4] 2> 415778 T1235 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 415778 T1235 oashc.HttpShardHandl
[...truncated too long message...]
/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 487503 T1935 C2109 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 487504 T1914 C2108 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 487510 T1915 C2108 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4] 2> 487511 T1942 C2109 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 487512 T1235 oas.SolrTestCaseJ4.tearDown ###Ending doTestSnapPullWithMasterUrl
[junit4] 2> 487522 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1161699436
[junit4] 2> 487526 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@201cca73
[junit4] 2> 487526 T1235 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=496,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 487526 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 487526 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 487526 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 487527 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 487528 T1235 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 487528 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021197\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021197\master\collection1\data\;done=false>>]
[junit4] 2> 487528 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021197\master\collection1\data\
[junit4] 2> 487528 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021197\master\collection1\data\index.20131011000703085 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021197\master\collection1\data\index.20131011000703085;done=false>>]
[junit4] 2> 487528 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021197\master\collection1\data\index.20131011000703085
[junit4] 2> 487529 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 487594 T1235 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=776418267
[junit4] 2> 487596 T1235 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@23f54ba5
[junit4] 2> 487596 T1235 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 487597 T1235 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 487597 T1235 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 487597 T1235 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 487601 T1235 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 487601 T1235 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 487602 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021336\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021336\slave\collection1\data\;done=false>>]
[junit4] 2> 487602 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021336\slave\collection1\data\
[junit4] 2> 487602 T1235 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021336\slave\collection1\data\index.20131011000702843 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021336\slave\collection1\data\index.20131011000702843;done=false>>]
[junit4] 2> 487602 T1235 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381486021336\slave\collection1\data\index.20131011000702843
[junit4] 2> 487602 T1235 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 487687 T1235 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene45: {newname=MockVariableIntBlock(baseBlockSize=112), id=MockVariableIntBlock(baseBlockSize=112), name=MockVariableIntBlock(baseBlockSize=112)}, docValues:{}, sim=DefaultSimilarity, locale=ar_YE, timezone=Etc/GMT+10
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=33690280,total=111419392
[junit4] 2> NOTE: All tests run in this JVM: [SliceStateUpdateTest, TestFastWriter, HdfsSyncSliceTest, TestSolrDeletionPolicy2, RequestHandlersTest, TestFiltering, OverseerCollectionProcessorTest, BasicZkTest, PrimUtilsTest, ReturnFieldsTest, BinaryUpdateRequestHandlerTest, TestShardHandlerFactory, DistributedSpellCheckComponentTest, RecoveryZkTest, OutputWriterTest, CircularListTest, TestCodecSupport, TestJmxMonitoredMap, NumericFieldsTest, MultiTermTest, TestDefaultSimilarityFactory, TestJoin, SliceStateTest, StatsComponentTest, TestReloadAndDeleteDocs, TestCSVResponseWriter, MoreLikeThisHandlerTest, TestXIncludeConfig, TestNonNRTOpen, BadCopyFieldTest, TestCopyFieldCollectionResource, UUIDFieldTest, XsltUpdateRequestHandlerTest, SolrCmdDistributorTest, SyncSliceTest, CoreAdminHandlerTest, TestSolrCoreProperties, AssignTest, CurrencyFieldOpenExchangeTest, TestTrie, LegacyHTMLStripCharFilterTest, EchoParamsTest, CurrencyFieldXmlFileTest, TestHashPartitioner, TestElisionMultitermQuery, SolrIndexSplitterTest, DebugComponentTest, TestManagedSchema, TestSchemaSimilarityResource, SignatureUpdateProcessorFactoryTest, TestSolrQueryParserResource, TestRandomDVFaceting, TestManagedSchemaFieldResource, TestRecoveryHdfs, SolrCoreTest, TestFieldCollectionResource, TestCollationField, TestFunctionQuery, TestRealTimeGet, SearchHandlerTest, TestReplicationHandler]
[junit4] Completed in 78.98s, 14 tests, 1 failure <<< FAILURES!
[...truncated 823 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:428: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:408: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\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:451: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 327 suites, 1453 tests, 1 failure, 43 ignored (11 assumptions)
Total time: 59 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b106 -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.6.0_45) - Build #
3272 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3272/
Java: 64bit/jdk1.6.0_45 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
collection already exists: awholynewcollection_1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: collection already exists: awholynewcollection_1
at __randomizedtesting.SeedInfo.seed([5F5C3B8B1E7A91AE:DEBAB5936925F192]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:463)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:268)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:623)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1580)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1606)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:563)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:194)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:662)
Build Log:
[...truncated 9681 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> 1870171 T4836 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_dgy/s
[junit4] 2> 1870176 T4836 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-CollectionsAPIDistributedZkTest-1381513582030
[junit4] 2> 1870178 T4836 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 1870179 T4837 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1870275 T4836 oasc.ZkTestServer.run start zk server on port:60649
[junit4] 2> 1870275 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1870282 T4843 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@376645b8 name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1870282 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1870282 T4836 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1870291 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1870294 T4845 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f32c73b name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1870295 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1870295 T4836 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1870301 T4836 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1870308 T4836 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1870313 T4836 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1870320 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1870320 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1870330 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1870331 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1870338 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1870338 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1870346 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1870346 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1870352 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1870352 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1870359 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1870359 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1870366 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1870367 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1870372 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1870372 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1870380 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1870380 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1870386 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1870386 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> Using old style solr.xml
[junit4] 2> 1870396 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1870399 T4847 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@576e78fc name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1870399 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1870399 T4836 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1870406 T4838 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x141a8a055d70002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 1870407 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1870409 T4849 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ea391c9 name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1870409 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1870411 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf2/solrconfig.xml
[junit4] 2> 1870412 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 1870420 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf2/schema.xml
[junit4] 2> 1870420 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 1870440 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1870441 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1870448 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf2/stopwords.txt
[junit4] 2> 1870448 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 1870455 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf2/protwords.txt
[junit4] 2> 1870455 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 1870461 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf2/currency.xml
[junit4] 2> 1870462 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 1870468 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
[junit4] 2> 1870468 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 1870474 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1870475 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1870481 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf2/old_synonyms.txt
[junit4] 2> 1870482 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 1870488 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf2/synonyms.txt
[junit4] 2> 1870489 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 1870494 T4836 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\elevate.xml to /configs/conf2/elevate.xml
[junit4] 2> 1870495 T4836 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 1870964 T4836 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1870967 T4836 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60662
[junit4] 2> 1870967 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1870967 T4836 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1870968 T4836 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356
[junit4] 2> 1870968 T4836 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\'
[junit4] 2> 1871002 T4836 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\solr.xml
[junit4] 2> 1871097 T4836 oasc.CoreContainer.<init> New CoreContainer 1184547556
[junit4] 2> 1871097 T4836 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\]
[junit4] 2> 1871099 T4836 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1871099 T4836 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1871099 T4836 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1871100 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1871100 T4836 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1871101 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1871101 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1871101 T4836 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1871102 T4836 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1871102 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1871108 T4836 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1871108 T4836 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1871109 T4836 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60649/solr
[junit4] 2> 1871109 T4836 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1871110 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1871117 T4860 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a712af2 name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1871118 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1871124 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1871124 T4838 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x141a8a055d70004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 1871129 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1871132 T4862 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e46ccc0 name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1871132 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1871134 T4836 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1871144 T4836 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1871151 T4836 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1871155 T4836 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60662__dgy%2Fs
[junit4] 2> 1871157 T4836 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60662__dgy%2Fs
[junit4] 2> 1871164 T4836 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1871175 T4836 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1871180 T4836 oasc.Overseer.start Overseer (id=90538874112507909-127.0.0.1:60662__dgy%2Fs-n_0000000000) starting
[junit4] 2> 1871188 T4836 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1871196 T4864 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1871198 T4836 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1871206 T4836 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1871209 T4836 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1871216 T4863 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1871219 T4836 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1871220 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1871220 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1871222 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 1871224 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1871227 T4867 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a4a9bed name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1871228 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1871230 T4836 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1871236 T4836 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 1871700 T4836 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1871703 T4836 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60674
[junit4] 2> 1871703 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1871703 T4836 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1871704 T4836 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088
[junit4] 2> 1871704 T4836 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\'
[junit4] 2> 1871750 T4836 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\solr.xml
[junit4] 2> 1871839 T4836 oasc.CoreContainer.<init> New CoreContainer 662631481
[junit4] 2> 1871840 T4836 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\]
[junit4] 2> 1871842 T4836 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1871843 T4836 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1871843 T4836 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1871844 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1871844 T4836 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1871845 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1871845 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1871845 T4836 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1871846 T4836 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1871846 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1871850 T4836 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1871850 T4836 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1871851 T4836 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60649/solr
[junit4] 2> 1871851 T4836 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1871853 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1871863 T4878 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7fd3de70 name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1871864 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1871869 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1871869 T4838 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x141a8a055d70007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 1871872 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1871875 T4880 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@756efc2e name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1871876 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1871885 T4836 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1872838 T4836 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60674__dgy%2Fs
[junit4] 2> 1872840 T4836 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60674__dgy%2Fs
[junit4] 2> 1872850 T4880 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1872850 T4862 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1872850 T4867 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1872861 T4836 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1872862 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1872862 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1873350 T4836 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1873353 T4836 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60683
[junit4] 2> 1873354 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1873354 T4836 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1873354 T4836 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714
[junit4] 2> 1873355 T4836 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\'
[junit4] 2> 1873385 T4836 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\solr.xml
[junit4] 2> 1873479 T4836 oasc.CoreContainer.<init> New CoreContainer 1845065053
[junit4] 2> 1873480 T4836 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\]
[junit4] 2> 1873482 T4836 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1873483 T4836 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1873483 T4836 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1873484 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1873484 T4836 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1873485 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1873485 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1873486 T4836 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1873486 T4836 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1873487 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1873491 T4836 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1873491 T4836 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1873492 T4836 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60649/solr
[junit4] 2> 1873492 T4836 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1873494 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1873502 T4892 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14886513 name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1873502 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1873505 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1873505 T4838 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x141a8a055d70009, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 1873508 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1873511 T4894 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b3c9766 name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1873512 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1873520 T4836 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1874472 T4836 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60683__dgy%2Fs
[junit4] 2> 1874475 T4836 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60683__dgy%2Fs
[junit4] 2> 1874482 T4862 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1874483 T4880 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1874485 T4867 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1874485 T4894 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1874497 T4836 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1874497 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1874498 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1874890 T4836 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1874893 T4836 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60692
[junit4] 2> 1874893 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1874893 T4836 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1874895 T4836 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1381513586351
[junit4] 2> 1874895 T4836 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1381513586351\'
[junit4] 2> 1874925 T4836 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1381513586351\solr.xml
[junit4] 2> 1875013 T4836 oasc.CoreContainer.<init> New CoreContainer 1413359714
[junit4] 2> 1875013 T4836 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1381513586351\]
[junit4] 2> 1875015 T4836 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1875015 T4836 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1875015 T4836 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1875015 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1875016 T4836 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1875016 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1875018 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1875018 T4836 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1875018 T4836 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1875018 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1875022 T4836 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1875022 T4836 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1875023 T4836 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60649/solr
[junit4] 2> 1875023 T4836 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1875025 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1875028 T4906 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ccd6523 name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1875028 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1875031 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1875033 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1875036 T4908 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ad9697e name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1875036 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1875044 T4836 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1875901 T4836 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60692__dgy%2Fs
[junit4] 2> 1875904 T4836 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60692__dgy%2Fs
[junit4] 2> 1875910 T4894 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1875911 T4867 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1875912 T4880 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1875914 T4862 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1875914 T4908 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1875924 T4836 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1875924 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1875925 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1876302 T4836 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1876305 T4836 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60701
[junit4] 2> 1876305 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1876306 T4836 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1876307 T4836 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778
[junit4] 2> 1876307 T4836 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\'
[junit4] 2> 1876347 T4836 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\solr.xml
[junit4] 2> 1876420 T4836 oasc.CoreContainer.<init> New CoreContainer 1104261631
[junit4] 2> 1876421 T4836 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\]
[junit4] 2> 1876422 T4836 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1876422 T4836 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1876423 T4836 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1876423 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1876424 T4836 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1876424 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1876424 T4836 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1876424 T4836 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1876425 T4836 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1876426 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1876429 T4836 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1876430 T4836 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1876430 T4836 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60649/solr
[junit4] 2> 1876431 T4836 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1876432 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1876440 T4920 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2dfcfe1b name:ZooKeeperConnection Watcher:127.0.0.1:60649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1876440 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1876443 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1876443 T4838 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x141a8a055d7000d, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 1876446 T4836 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1876448 T4922 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@180bdf9d name:ZooKeeperConnection Watcher:127.0.0.1:60649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1876449 T4836 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1876457 T4836 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1877314 T4836 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60701__dgy%2Fs
[junit4] 2> 1877317 T4836 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60701__dgy%2Fs
[junit4] 2> 1877327 T4894 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1877327 T4867 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1877327 T4880 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1877328 T4908 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1877328 T4922 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1877328 T4862 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1877339 T4836 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1877340 T4836 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1877340 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1877342 T4836 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1877344 T4873 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=2&collection.configName=conf1&name=nodes_used_collection&replicationFactor=2&action=CREATE&wt=javabin&version=2
[junit4] 2> 1877359 T4862 oasc.DistributedQueue$LatchChildWatcher.process Watcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1877362 T4862 oasc.DistributedQueue$LatchChildWatcher.process Watcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1877362 T4864 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "name":"nodes_used_collection",
[junit4] 2> "replicationFactor":"2"}
[junit4] 2> 1877362 T4864 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "name":"nodes_used_collection",
[junit4] 2> "replicationFactor":"2"}
[junit4] 2> 1877989 T4863 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1877991 T4863 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: nodes_used_collection
[junit4] 2> 1877992 T4863 oasc.Overseer$ClusterStateUpdater.createCollection Create collection nodes_used_collection with shards [shard1, shard2]
[junit4] 2> 1878002 T4922 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> 1878003 T4862 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> 1878003 T4908 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> 1878003 T4894 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> 1878004 T4880 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> 1878005 T4867 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> 1878052 T4864 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2] , repFactor : 2
[junit4] 2> 1878052 T4864 oasc.OverseerCollectionProcessor.createCollection Creating shard nodes_used_collection_shard1_replica1 as part of slice shard1 of collection nodes_used_collection on 127.0.0.1:60662__dgy%2Fs
[junit4] 2> 1878052 T4864 oasc.OverseerCollectionProcessor.createCollection Creating shard nodes_used_collection_shard1_replica2 as part of slice shard1 of collection nodes_used_collection on 127.0.0.1:60683__dgy%2Fs
[junit4] 2> 1878054 T4864 oasc.OverseerCollectionProcessor.createCollection Creating shard nodes_used_collection_shard2_replica1 as part of slice shard2 of collection nodes_used_collection on 127.0.0.1:60701__dgy%2Fs
[junit4] 2> 1878055 T4864 oasc.OverseerCollectionProcessor.createCollection Creating shard nodes_used_collection_shard2_replica2 as part of slice shard2 of collection nodes_used_collection on 127.0.0.1:60674__dgy%2Fs
[junit4] 2> 1878061 T4857 oasc.ZkController.publish publishing core=nodes_used_collection_shard1_replica1 state=down
[junit4] 2> 1878062 T4916 oasc.ZkController.publish publishing core=nodes_used_collection_shard2_replica1 state=down
[junit4] 2> 1878062 T4875 oasc.ZkController.publish publishing core=nodes_used_collection_shard2_replica2 state=down
[junit4] 2> 1878064 T4885 oasc.ZkController.publish publishing core=nodes_used_collection_shard1_replica2 state=down
[junit4] 2> 1878066 T4916 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1878066 T4857 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1878067 T4875 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1878068 T4885 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1879298 T4863 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1879300 T4863 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:60662/_dgy/s",
[junit4] 2> "core":"nodes_used_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60662__dgy%2Fs",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"nodes_used_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 1879312 T4863 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:60701/_dgy/s",
[junit4] 2> "core":"nodes_used_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60701__dgy%2Fs",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"nodes_used_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 1879323 T4863 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:60674/_dgy/s",
[junit4] 2> "core":"nodes_used_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60674__dgy%2Fs",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"nodes_used_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 1879332 T4863 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:60683/_dgy/s",
[junit4] 2> "core":"nodes_used_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60683__dgy%2Fs",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"nodes_used_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node4"}
[junit4] 2> 1879344 T4922 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> 1879344 T4908 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> 1879345 T4880 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> 1879345 T4894 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> 1879346 T4862 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> 1879346 T4867 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> 1879769 T4916 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for nodes_used_collection_shard2_replica1
[junit4] 2> 1879770 T4857 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for nodes_used_collection_shard1_replica1
[junit4] 2> 1879770 T4875 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for nodes_used_collection_shard2_replica2
[junit4] 2> 1879770 T4916 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\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\solr.xml
[junit4] 2> 1879771 T4916 oasc.CoreContainer.create Creating SolrCore 'nodes_used_collection_shard2_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1
[junit4] 2> 1879771 T4916 oasc.ZkController.createCollectionZkNode Check for collection zkNode:nodes_used_collection
[junit4] 2> 1879772 T4875 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\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\solr.xml
[junit4] 2> 1879773 T4875 oasc.CoreContainer.create Creating SolrCore 'nodes_used_collection_shard2_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2
[junit4] 2> 1879773 T4875 oasc.ZkController.createCollectionZkNode Check for collection zkNode:nodes_used_collection
[junit4] 2> 1879774 T4916 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:nodes_used_collection
[junit4] 2> 1879774 T4916 oascc.SolrZkClient.makePath makePath: /collections/nodes_used_collection
[junit4] 2> 1879773 T4885 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for nodes_used_collection_shard1_replica2
[junit4] 2> 1879773 T4857 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\solr.xml
[junit4] 2> 1879775 T4857 oasc.CoreContainer.create Creating SolrCore 'nodes_used_collection_shard1_replica1' using instanceDir: .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1
[junit4] 2> 1879776 T4857 oasc.ZkController.createCollectionZkNode Check for collection zkNode:nodes_used_collection
[junit4] 2> 1879778 T4885 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\solr.xml
[junit4] 2> 1879778 T4885 oasc.CoreContainer.create Creating SolrCore 'nodes_used_collection_shard1_replica2' using instanceDir: .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2
[junit4] 2> 1879778 T4885 oasc.ZkController.createCollectionZkNode Check for collection zkNode:nodes_used_collection
[junit4] 2> 1879780 T4875 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:nodes_used_collection
[junit4] 2> 1879780 T4875 oascc.SolrZkClient.makePath makePath: /collections/nodes_used_collection
[junit4] 2> 1879781 T4857 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:nodes_used_collection
[junit4] 2> 1879781 T4857 oascc.SolrZkClient.makePath makePath: /collections/nodes_used_collection
[junit4] 2> 1879784 T4885 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:nodes_used_collection
[junit4] 2> 1879785 T4885 oascc.SolrZkClient.makePath makePath: /collections/nodes_used_collection
[junit4] 2> 1879790 T4916 oasc.ZkController.readConfigName Load collection config from:/collections/nodes_used_collection
[junit4] 2> 1879793 T4857 oasc.ZkController.readConfigName Load collection config from:/collections/nodes_used_collection
[junit4] 2> 1879794 T4875 oasc.ZkController.readConfigName Load collection config from:/collections/nodes_used_collection
[junit4] 2> 1879797 T4916 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\'
[junit4] 2> 1879800 T4885 oasc.ZkController.readConfigName Load collection config from:/collections/nodes_used_collection
[junit4] 2> 1879804 T4857 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\'
[junit4] 2> 1879814 T4875 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\'
[junit4] 2> 1879831 T4885 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\'
[junit4] 2> 1879908 T4916 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1879996 T4857 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1880031 T4916 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1880093 T4916 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1880121 T4875 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1880123 T4916 oass.IndexSchema.readSchema [nodes_used_collection_shard2_replica1] Schema name=test
[junit4] 2> 1880207 T4885 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1880249 T4857 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1880450 T4885 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1880467 T4857 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1880476 T4857 oass.IndexSchema.readSchema [nodes_used_collection_shard1_replica1] Schema name=test
[junit4] 2> 1880510 T4875 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1880610 T4885 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1880650 T4885 oass.IndexSchema.readSchema [nodes_used_collection_shard1_replica2] Schema name=test
[junit4] 2> 1880687 T4875 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1880770 T4875 oass.IndexSchema.readSchema [nodes_used_collection_shard2_replica2] Schema name=test
[junit4] 2> 1881973 T4916 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1881974 T4916 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1881976 T4916 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1882021 T4916 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1882027 T4916 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1882754 T4857 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1882754 T4857 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1882755 T4857 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1882763 T4857 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1882766 T4857 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1882976 T4875 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1882976 T4875 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1882977 T4875 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1882984 T4885 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1882985 T4885 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1882986 T4885 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1882986 T4875 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1882991 T4875 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1882993 T4885 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1882997 T4916 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1882998 T4885 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1883009 T4916 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883033 T4916 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883044 T4916 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883045 T4916 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883045 T4916 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883054 T4916 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883055 T4916 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883056 T4916 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1883056 T4916 oasc.SolrCore.<init> [nodes_used_collection_shard2_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\data\
[junit4] 2> 1883057 T4916 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2add5681
[junit4] 2> 1883059 T4916 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\data\
[junit4] 2> 1883059 T4916 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\data\index/
[junit4] 2> 1883060 T4916 oasc.SolrCore.initIndex WARN [nodes_used_collection_shard2_replica1] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1883061 T4916 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\nodes_used_collection_shard2_replica1\data\index
[junit4] 2> 1883061 T4916 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=72.705078125, floorSegmentMB=1.0947265625, forceMergeDeletesPctAllowed=11.090711797547737, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1883064 T4916 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@507c60eb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f59d265),segFN=segments_1,generation=1}
[junit4] 2> 1883065 T4916 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1883073 T4916 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1883074 T4916 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1883075 T4916 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1883075 T4857 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883075 T4916 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1883078 T4916 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1883079 T4916 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1883079 T4916 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1883080 T4916 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1883080 T4916 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1883081 T4916 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1883083 T4916 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1883083 T4916 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1883084 T4916 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1883084 T4916 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1883086 T4916 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1883095 T4916 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1883099 T4857 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883104 T4916 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1883105 T4916 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1883107 T4885 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883117 T4857 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883134 T4885 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883138 T4857 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883140 T4857 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883141 T4857 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883151 T4875 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883162 T4885 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883164 T4857 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883165 T4857 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883167 T4857 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1883167 T4857 oasc.SolrCore.<init> [nodes_used_collection_shard1_replica1] Opening new SolrCore at .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\, dataDir=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\data\
[junit4] 2> 1883168 T4857 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2add5681
[junit4] 2> 1883170 T4857 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\data\
[junit4] 2> 1883171 T4857 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\data\index/
[junit4] 2> 1883171 T4857 oasc.SolrCore.initIndex WARN [nodes_used_collection_shard1_replica1] Solr index directory '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1883172 T4857 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1381513582356\nodes_used_collection_shard1_replica1\data\index
[junit4] 2> 1883173 T4857 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=72.705078125, floorSegmentMB=1.0947265625, forceMergeDeletesPctAllowed=11.090711797547737, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1883175 T4857 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@38f88e6e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@610ce5b),segFN=segments_1,generation=1}
[junit4] 2> 1883175 T4857 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1883183 T4857 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1883184 T4857 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1883187 T4857 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1883188 T4857 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1883188 T4857 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1883189 T4857 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1883190 T4857 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1883192 T4857 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1883192 T4857 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1883193 T4857 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1883194 T4857 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1883194 T4857 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1883195 T4857 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1883197 T4857 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1883197 T4857 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1883200 T4857 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1883211 T4857 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1883211 T4857 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1883212 T4857 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5708593701702996]
[junit4] 2> 1883214 T4857 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@38f88e6e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@610ce5b),segFN=segments_1,generation=1}
[junit4] 2> 1883216 T4857 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1883216 T4857 oass.SolrIndexSearcher.<init> Opening Searcher@5e144dc9 main
[junit4] 2> 1883219 T4885 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883220 T4885 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883220 T4885 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883227 T4875 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1883262 T4916 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5708593701702996]
[junit4] 2> 1883264 T4916 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@507c60eb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f59d265),segFN=segments_1,generation=1}
[junit4] 2> 1883276 T4885 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883277 T4885 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883278 T4885 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1883279 T4885 oasc.SolrCore.<init> [nodes_used_collection_shard1_replica2] Opening new SolrCore at .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\, dataDir=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\data\
[junit4] 2> 1883275 T4857 oasc.CoreContainer.registerCore registering core: nodes_used_collection_shard1_replica1
[junit4] 2> 1883280 T4857 oasc.ZkController.register Register replica - core:nodes_used_collection_shard1_replica1 address:http://127.0.0.1:60662/_dgy/s collection:nodes_used_collection shard:shard1
[junit4] 2> 1883281 T4857 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 1883272 T4929 oasc.SolrCore.registerSearcher [nodes_used_collection_shard1_replica1] Registered new searcher Searcher@5e144dc9 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1883280 T4916 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1883280 T4885 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2add5681
[junit4] 2> 1883285 T4916 oass.SolrIndexSearcher.<init> Opening Searcher@364b96e5 main
[junit4] 2> 1883286 T4875 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883289 T4857 oascc.SolrZkClient.makePath makePath: /collections/nodes_used_collection/leader_elect/shard1/election
[junit4] 2> 1883291 T4928 oasc.SolrCore.registerSearcher [nodes_used_collection_shard2_replica1] Registered new searcher Searcher@364b96e5 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1883286 T4885 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\data\
[junit4] 2> 1883295 T4916 oasc.CoreContainer.registerCore registering core: nodes_used_collection_shard2_replica1
[junit4] 2> 1883296 T4916 oasc.ZkController.register Register replica - core:nodes_used_collection_shard2_replica1 address:http://127.0.0.1:60701/_dgy/s collection:nodes_used_collection shard:shard2
[junit4] 2> 1883296 T4916 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 1883296 T4875 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883298 T4875 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883300 T4875 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1883301 T4916 oascc.SolrZkClient.makePath makePath: /collections/nodes_used_collection/leader_elect/shard2/election
[junit4] 2> 1883304 T4885 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\data\index/
[junit4] 2> 1883305 T4885 oasc.SolrCore.initIndex WARN [nodes_used_collection_shard1_replica2] Solr index directory '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 1883315 T4885 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1381513584714\nodes_used_collection_shard1_replica2\data\index
[junit4] 2> 1883316 T4885 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=72.705078125, floorSegmentMB=1.0947265625, forceMergeDeletesPctAllowed=11.090711797547737, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1883319 T4885 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@538bcfd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5241b2fb),segFN=segments_1,generation=1}
[junit4] 2> 1883321 T4885 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1883320 T4875 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1883322 T4875 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1883324 T4875 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1883324 T4875 oasc.SolrCore.<init> [nodes_used_collection_shard2_replica2] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\data\
[junit4] 2> 1883325 T4875 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2add5681
[junit4] 2> 1883326 T4885 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1883329 T4885 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1883330 T4885 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1883327 T4875 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\data\
[junit4] 2> 1883333 T4875 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\data\index/
[junit4] 2> 1883333 T4875 oasc.SolrCore.initIndex WARN [nodes_used_collection_shard2_replica2] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 1883332 T4885 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1883335 T4885 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1883335 T4885 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1883335 T4875 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1381513583088\nodes_used_collection_shard2_replica2\data\index
[junit4] 2> 1883337 T4875 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=72.705078125, floorSegmentMB=1.0947265625
[...truncated too long message...]
rkspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\awholynewcollection_1_shard4_replica2\data\ [CachedDir<<refCount=0;path=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\awholynewcollection_1_shard4_replica2\data\;done=false>>]
[junit4] 2> 1969343 T4836 oasc.CachingDirectoryFactory.close Closing directory: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1381513587778\awholynewcollection_1_shard4_replica2\data\
[junit4] 2> 1969348 T5080 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90538874112507918-127.0.0.1:60701__dgy%2Fs-n_0000000004) am no longer a leader.
[junit4] 2> 1969351 T4922 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1969363 T4836 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/_dgy/s,null}
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-1381513582029\zookeeper\server1\data\version-2\log.1 FAILED !!!!!
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-1381513582029\zookeeper\server1\data\version-2 FAILED !!!!!
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-1381513582029\zookeeper\server1\data FAILED !!!!!
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-1381513582029\zookeeper\server1 FAILED !!!!!
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-1381513582029\zookeeper FAILED !!!!!
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-1381513582029 FAILED !!!!!
[junit4] 2> 1969430 T4836 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 1969434 T4836 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60649 60649
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=5F5C3B8B1E7A91AE -Dtests.slow=true -Dtests.locale=nl -Dtests.timezone=PRT -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 102s | CollectionsAPIDistributedZkTest.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: collection already exists: awholynewcollection_1
[junit4] > at __randomizedtesting.SeedInfo.seed([5F5C3B8B1E7A91AE:DEBAB5936925F192]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:463)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:268)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:623)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1580)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1606)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:563)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:194)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:662)
[junit4] 2> 1971962 T4836 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=nl, timezone=PRT
[junit4] 2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_45 (64-bit)/cpus=2,threads=1,free=192725104,total=412745728
[junit4] 2> NOTE: All tests run in this JVM: [PrimitiveFieldTypeTest, TestJmxMonitoredMap, SliceStateTest, TestSort, TestAddFieldRealTimeGet, FileUtilsTest, TermVectorComponentTest, AutoCommitTest, RecoveryZkTest, BinaryUpdateRequestHandlerTest, MultiTermTest, TestQuerySenderListener, DisMaxRequestHandlerTest, TestSolrCoreProperties, TestSchemaSimilarityResource, TestBadConfig, SolrInfoMBeanTest, IndexSchemaTest, ShardRoutingCustomTest, ScriptEngineTest, NoCacheHeaderTest, DocValuesTest, DistanceFunctionTest, RAMDirectoryFactoryTest, TestFoldingMultitermQuery, ShowFileRequestHandlerTest, FastVectorHighlighterTest, SolrRequestParserTest, TestReversedWildcardFilterFactory, TestSolrQueryParserResource, PrimUtilsTest, SolrPluginUtilsTest, UnloadDistributedZkTest, TestRTGBase, TestRandomDVFaceting, BlockDirectoryTest, SolrCmdDistributorTest, XsltUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, PathHierarchyTokenizerFactoryTest, CachingDirectoryFactoryTest, ZkControllerTest, TestMultiCoreConfBootstrap, SolrIndexConfigTest, DirectUpdateHandlerOptimizeTest, TestAtomicUpdateErrorCases, TestSolrQueryParser, TestCloudManagedSchema, BadCopyFieldTest, TestExtendedDismaxParser, SpatialFilterTest, DirectSolrSpellCheckerTest, PeerSyncTest, TestZkChroot, SolrCoreCheckLockOnStartupTest, TestWriterPerf, TestOmitPositions, TestSolrDeletionPolicy2, URLClassifyProcessorTest, TestManagedSchemaFieldResource, SolrTestCaseJ4Test, SchemaVersionSpecificBehaviorTest, BasicDistributedZkTest, SystemInfoHandlerTest, TestPseudoReturnFields, ShardSplitTest, SolrIndexSplitterTest, TestCSVLoader, StatelessScriptUpdateProcessorFactoryTest, TestSearchPerf, TestComponentsName, LeaderElectionIntegrationTest, SuggesterFSTTest, XmlUpdateRequestHandlerTest, TestDocumentBuilder, HdfsBasicDistributedZkTest, CoreContainerCoreInitFailuresTest, CacheHeaderTest, HardAutoCommitTest, TimeZoneUtilsTest, ReturnFieldsTest, UpdateParamsTest, TestCodecSupport, CopyFieldTest, TestLFUCache, SolrXmlInZkTest, PingRequestHandlerTest, TestConfig, AddBlockUpdateTest, PolyFieldTest, TestBM25SimilarityFactory, TestPartialUpdateDeduplication, RequestHandlersTest, DirectUpdateHandlerTest, SoftAutoCommitTest, TestTrie, HighlighterConfigTest, CoreAdminHandlerTest, SpellCheckComponentTest, TestShardHandlerFactory, TestGroupingSearch, TestElisionMultitermQuery, TestBinaryResponseWriter, TestInfoStreamLogging, BadComponentTest, TestFuzzyAnalyzedSuggestions, AssignTest, TestUpdate, TermVectorComponentDistributedTest, DeleteShardTest, TestSweetSpotSimilarityFactory, HdfsUnloadDistributedZkTest, TestDefaultSearchFieldResource, TestIBSimilarityFactory, HdfsDirectoryTest, TestNoOpRegenerator, TestDefaultSimilarityFactory, TestCoreContainer, TestAnalyzedSuggestions, TestReplicationHandler, ClusterStateTest, TestIndexSearcher, TestSolr4Spatial, TestQuerySenderNoQuery, DateFieldTest, SyncSliceTest, TestFiltering, TestCloudManagedSchemaAddField, TestCoreDiscovery, TestSolrDeletionPolicy1, TestStressReorder, HdfsSyncSliceTest, TestRecovery, SuggesterWFSTTest, DirectSolrConnectionTest, TestSerializedLuceneMatchVersion, StatsComponentTest, TestUtils, TestRandomFaceting, DocumentAnalysisRequestHandlerTest, AliasIntegrationTest, HdfsChaosMonkeySafeLeaderTest, LeaderElectionTest, MinimalSchemaTest, DocValuesMissingTest, HdfsRecoveryZkTest, OpenExchangeRatesOrgProviderTest, TestPerFieldSimilarity, QueryElevationComponentTest, TestSolrQueryParserDefaultOperatorResource, TestRealTimeGet, TestReloadAndDeleteDocs, IndexBasedSpellCheckerTest, SynonymTokenizerTest, TestFastWriter, SolrCoreTest, SignatureUpdateProcessorFactoryTest, MBeansHandlerTest, TestLRUCache, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest]
[junit4] Completed in 101.84s, 1 test, 1 error <<< FAILURES!
[...truncated 508 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:428: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:408: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\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:451: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 327 suites, 1453 tests, 1 error, 42 ignored (14 assumptions)
Total time: 81 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0_45 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure