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/08/22 13:24:05 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.6.0_45) - Build #
3106 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3106/
Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Thu Aug 22 06:28:19 AMT 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Thu Aug 22 06:28:19 AMT 2013
at __randomizedtesting.SeedInfo.seed([136102EB1A77D795:C8CA022D1F5FBE26]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1513)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:817)
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 8914 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 141436 T485 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 141450 T485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 141455 T485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63512
[junit4] 2> 141455 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 141455 T485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 141456 T485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master
[junit4] 2> 141456 T485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\'
[junit4] 2> 141483 T485 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-1377167256394\master\solr.xml
[junit4] 2> 141484 T485 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\solr.xml does not exist, using default configuration
[junit4] 2> 141571 T485 oasc.CoreContainer.<init> New CoreContainer 1731568834
[junit4] 2> 141571 T485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\]
[junit4] 2> 141573 T485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 141574 T485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 141574 T485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 141574 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 141574 T485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 141574 T485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 141576 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 141576 T485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 141576 T485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 141577 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 141600 T495 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1
[junit4] 2> 141600 T495 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\'
[junit4] 2> 141657 T495 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 141712 T495 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 141713 T495 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 141719 T495 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 141739 T495 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 141741 T495 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 141742 T495 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\data\
[junit4] 2> 141742 T495 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 141743 T495 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-1377167256394\master\collection1\data\
[junit4] 2> 141743 T495 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-1377167256394\master\collection1\data\index/
[junit4] 2> 141743 T495 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-1377167256394\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 141744 T495 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-1377167256394\master\collection1\data\index
[junit4] 2> 141744 T495 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=81.6240234375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=11.164015638173343, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 141745 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@76566fb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@292898f5),segFN=segments_1,generation=1}
[junit4] 2> 141745 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141746 T495 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 141747 T495 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 141747 T495 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 141747 T495 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 141747 T495 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 141748 T495 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 141748 T495 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 141752 T495 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 141755 T495 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 141756 T495 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 141756 T495 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7914529025739787]
[junit4] 2> 141757 T495 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@76566fb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@292898f5),segFN=segments_1,generation=1}
[junit4] 2> 141757 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141758 T495 oass.SolrIndexSearcher.<init> Opening Searcher@541d4bce main
[junit4] 2> 141758 T495 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 141758 T495 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 141759 T495 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 141761 T485 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 141761 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 141761 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 141784 T485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 141792 T496 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@541d4bce main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 141796 T485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63515
[junit4] 2> 141796 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 141797 T485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 141798 T485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave
[junit4] 2> 141798 T485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\'
[junit4] 2> 141813 T485 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-1377167256730\slave\solr.xml
[junit4] 2> 141814 T485 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\solr.xml does not exist, using default configuration
[junit4] 2> 141883 T485 oasc.CoreContainer.<init> New CoreContainer 72098708
[junit4] 2> 141885 T485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\]
[junit4] 2> 141886 T485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 141886 T485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 141886 T485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 141887 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 141887 T485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 141888 T485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 141888 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 141889 T485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 141889 T485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 141890 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 141900 T507 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1
[junit4] 2> 141900 T507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1\'
[junit4] 2> 141950 T507 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 142007 T507 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 142007 T507 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 142014 T507 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 142032 T507 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 142034 T507 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 142034 T507 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1\data\
[junit4] 2> 142034 T507 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 142038 T507 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-1377167256730\slave\collection1\data\
[junit4] 2> 142039 T507 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-1377167256730\slave\collection1\data\index/
[junit4] 2> 142039 T507 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-1377167256730\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 142039 T507 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-1377167256730\slave\collection1\data\index
[junit4] 2> 142040 T507 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=81.6240234375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=11.164015638173343, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 142040 T507 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@683a412e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23cf68e9),segFN=segments_1,generation=1}
[junit4] 2> 142040 T507 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 142042 T507 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 142042 T507 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 142043 T507 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 142043 T507 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 142043 T507 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 142043 T507 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 142044 T507 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 142047 T507 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 142049 T507 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 142049 T507 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 142049 T507 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7914529025739787]
[junit4] 2> 142050 T507 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@683a412e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23cf68e9),segFN=segments_1,generation=1}
[junit4] 2> 142050 T507 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 142051 T507 oass.SolrIndexSearcher.<init> Opening Searcher@e9d222e main
[junit4] 2> 142055 T507 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 142055 T507 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 142055 T508 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e9d222e main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 142055 T485 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 142056 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 142057 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 142073 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=72098708
[junit4] 2> 142074 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3d2656d3
[junit4] 2> 142075 T485 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> 142076 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 142076 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 142077 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 142078 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 142079 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 142079 T485 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-1377167256730\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-1377167256730\slave\collection1\data\;done=false>>]
[junit4] 2> 142080 T485 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-1377167256730\slave\collection1\data\
[junit4] 2> 142080 T485 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-1377167256730\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-1377167256730\slave\collection1\data\index;done=false>>]
[junit4] 2> 142081 T485 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-1377167256730\slave\collection1\data\index
[junit4] 2> 142082 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 142162 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1731568834
[junit4] 2> 142163 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@bbf4061
[junit4] 2> 142163 T485 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> 142164 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 142164 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 142164 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 142166 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 142166 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 142166 T485 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-1377167256394\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-1377167256394\master\collection1\data\index;done=false>>]
[junit4] 2> 142167 T485 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-1377167256394\master\collection1\data\index
[junit4] 2> 142168 T485 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-1377167256394\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-1377167256394\master\collection1\data\;done=false>>]
[junit4] 2> 142168 T485 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-1377167256394\master\collection1\data\
[junit4] 2> 142169 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 142222 T485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 142225 T485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63518
[junit4] 2> 142226 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 142227 T485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 142227 T485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master
[junit4] 2> 142227 T485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\'
[junit4] 2> 142242 T485 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-1377167256394\master\solr.xml
[junit4] 2> 142242 T485 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\solr.xml does not exist, using default configuration
[junit4] 2> 142303 T485 oasc.CoreContainer.<init> New CoreContainer 1307088945
[junit4] 2> 142303 T485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\]
[junit4] 2> 142305 T485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 142305 T485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 142306 T485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 142306 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 142306 T485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 142307 T485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 142307 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 142307 T485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 142308 T485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 142308 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 142319 T520 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1
[junit4] 2> 142319 T520 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\'
[junit4] 2> 142373 T520 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 142434 T520 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 142435 T520 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 142441 T520 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 142461 T520 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 142462 T520 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 142463 T520 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\data\
[junit4] 2> 142463 T520 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 142464 T520 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-1377167256394\master\collection1\data\
[junit4] 2> 142464 T520 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-1377167256394\master\collection1\data\index/
[junit4] 2> 142464 T520 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-1377167256394\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 142465 T520 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-1377167256394\master\collection1\data\index
[junit4] 2> 142465 T520 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=81.6240234375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=11.164015638173343, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 142466 T520 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_1,generation=1}
[junit4] 2> 142466 T520 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 142468 T520 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 142468 T520 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 142469 T520 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 142469 T520 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 142469 T520 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 142469 T520 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 142470 T520 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 142471 T520 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 142474 T520 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 142474 T520 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 142475 T520 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7914529025739787]
[junit4] 2> 142476 T520 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_1,generation=1}
[junit4] 2> 142476 T520 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 142477 T520 oass.SolrIndexSearcher.<init> Opening Searcher@12d37bd8 main
[junit4] 2> 142477 T520 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 142477 T520 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 142478 T520 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 142478 T521 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12d37bd8 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 142478 T485 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 142479 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 142479 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C60 name=collection1 org.apache.solr.core.SolrCore@2d3d0953
[junit4] 2> 142496 T514 C60 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 142497 T514 C60 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 142501 T515 C60 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 142502 T515 C60 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_2,generation=2}
[junit4] 2> 142503 T515 C60 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 142503 T515 C60 oass.SolrIndexSearcher.<init> Opening Searcher@715be530 main
[junit4] 2> 142504 T515 C60 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 142504 T521 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@715be530 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 142504 T515 C60 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> ASYNC NEW_CORE C61 name=collection1 org.apache.solr.core.SolrCore@2d3d0953
[junit4] 2> 142517 T516 C61 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=6
[junit4] 2> 142521 T485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 142523 T485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63522
[junit4] 2> 142524 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 142524 T485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 142524 T485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave
[junit4] 2> 142524 T485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\'
[junit4] 2> 142540 T485 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-1377167256730\slave\solr.xml
[junit4] 2> 142541 T485 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\solr.xml does not exist, using default configuration
[junit4] 2> 142602 T485 oasc.CoreContainer.<init> New CoreContainer 1586766839
[junit4] 2> 142602 T485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\]
[junit4] 2> 142605 T485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 142605 T485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 142606 T485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 142606 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 142606 T485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 142606 T485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 142606 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 142607 T485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 142607 T485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 142607 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 142619 T534 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1
[junit4] 2> 142620 T534 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1\'
[junit4] 2> 142677 T534 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 142736 T534 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 142736 T534 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 142742 T534 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 142762 T534 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 142764 T534 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 142765 T534 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256730\slave\collection1\data\
[junit4] 2> 142765 T534 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 142765 T534 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-1377167256730\slave\collection1\data\
[junit4] 2> 142765 T534 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-1377167256730\slave\collection1\data\index/
[junit4] 2> 142766 T534 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-1377167256730\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 142767 T534 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-1377167256730\slave\collection1\data\index
[junit4] 2> 142767 T534 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=81.6240234375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=11.164015638173343, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 142767 T534 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@45051da4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55a330c3),segFN=segments_1,generation=1}
[junit4] 2> 142768 T534 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 142770 T534 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 142770 T534 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 142770 T534 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 142770 T534 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 142770 T534 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 142770 T534 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 142771 T534 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 142772 T534 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 142777 T534 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 142777 T534 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 142777 T534 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7914529025739787]
[junit4] 2> 142778 T534 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@45051da4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55a330c3),segFN=segments_1,generation=1}
[junit4] 2> 142778 T534 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 142778 T534 oass.SolrIndexSearcher.<init> Opening Searcher@1fd484c3 main
[junit4] 2> 142779 T534 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 142780 T534 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 142780 T535 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fd484c3 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 142781 T485 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 142781 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 142782 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C62 name=collection1 org.apache.solr.core.SolrCore@75b6ce70
[junit4] 2> 142795 T531 C62 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 142802 T517 C61 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 142804 T519 C61 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 142807 T514 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1
[junit4] 2> 142808 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\'
[junit4] 2> 142866 T514 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 142925 T514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 142926 T514 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 142933 T514 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 142953 T514 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 142954 T514 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167256394\master\collection1\data\
[junit4] 2> 142955 T514 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 142955 T514 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-1377167256394\master\collection1\data\index/
[junit4] 2> 142956 T514 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 142957 T514 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 142957 T514 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 142958 T514 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 142958 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 142958 T514 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 142959 T514 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 142961 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 142965 T514 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 142965 T514 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 142966 T514 oass.SolrIndexSearcher.<init> Opening Searcher@4a17babb main
[junit4] 2> 142966 T514 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 142966 T514 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 142967 T514 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 142967 T514 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 142967 T514 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 142967 T538 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4a17babb main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 142967 T514 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6012148638846866]
[junit4] 2> 142969 T514 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_2,generation=2}
[junit4] 2> 142969 T514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 142970 T514 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 142970 T514 oass.SolrIndexSearcher.<init> Opening Searcher@6b249338 main
[junit4] 2> 142971 T538 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b249338 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 142971 T514 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 142972 T514 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2d3d0953
[junit4] 2> 142972 T514 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=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 142975 T514 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 142976 T514 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=169
[junit4] 2> ASYNC NEW_CORE C63 name=collection1 org.apache.solr.core.SolrCore@2e3d787c
[junit4] 2> 142985 T515 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 142988 T516 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 142992 T518 C63 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 142997 T517 C63 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 2
[junit4] 2> 143000 T519 C63 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 143004 T519 C63 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12fc438b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bec15c7),segFN=segments_3,generation=3}
[junit4] 2> 143004 T519 C63 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 143006 T519 C63 oass.SolrIndexSearcher.<init> Opening Searcher@216f1cbc main
[junit4] 2> 143007 T519 C63 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 143007 T538 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@216f1cbc main{StandardDirectoryReader(segments_3:4:nrt _0(4.5):c2)}
[junit4] 2> 143008 T519 C63 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 2> 143035 T515 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 143036 T536 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 143036 T536 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 143036 T536 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 143069 T515 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=31
[junit4] 2> 143069 T536 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 143071 T536 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-1377167256730\slave\collection1\data\index.20130822062738027
[junit4] 2> 143071 T536 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@38d24866 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ebd2621)) fullCopy=false
[junit4] 2> 143074 T518 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 143078 T517 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 143082 T519 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 143085 T516 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 143086 T536 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 143087 T536 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 143088 T536 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 143088 T536 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 143097 T536 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 143097 T536 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=43, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 143100 T514 C63 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=90
[junit4] 2> 143104 T536 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@45051da4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55a330c3),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@45051da4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55a330c3),segFN=segments_3,generation=3}
[junit4] 2> 143105 T536 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 143105 T536 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 143107 T536 oass.SolrIndexSearcher.<init> Opening Searcher@443efcc7 main
[junit4] 2> 143107 T529 C62 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 143107 T535 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@443efcc7 main{StandardDirectoryReader(segments_3:4:nrt _0(4.5):c2)}
[junit4] 2> 143108 T485 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 143108 T536 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-1377167256730\slave\collection1\data\index.20130822062738027 [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-1377167256730\slave\collection1\data\index.20130822062738027;done=true>>]
[junit4] 2> 143108 T536 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-1377167256730\slave\collection1\data\index.20130822062738027
[junit4] 2> 143209 T525 C62 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> 143210 T485 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 143224 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1307088945
[junit4] 2> 143226 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2e3d787c
[junit4] 2> 143227 T485 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=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 143229 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 143230 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 143230 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 143234 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 143235 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 143236 T485 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-1377167256394\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-1377167256394\master\collection1\data\index;done=false>>]
[junit4] 2> 143238 T485 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-1377167256394\master\collection1\data\index
[junit4] 2> 143238 T485 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-1377167256394\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-1377167256394\master\collection1\data\;done=false>>]
[junit4] 2> 143239 T485 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-1377167256394\master\collection1\data\
[junit4] 2> 143240 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 143307 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1586766839
[junit4] 2> 143309 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@75b6ce70
[junit4] 2> 143311 T485 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> 143312 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 143312 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 143312 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 143316 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 143318 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 143319 T485 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-1377167256730\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-1377167256730\slave\collection1\data\index;done=false>>]
[junit4] 2> 143319 T485 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-1377167256730\slave\collection1\data\index
[junit4] 2> 143320 T485 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-1377167256730\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-1377167256730\slave\collection1\data\;done=false>>]
[junit4] 2> 143321 T485 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-1377167256730\slave\collection1\data\
[junit4] 2> 143324 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 143416 T485 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 143429 T485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 143433 T485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63527
[junit4] 2> 143434 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 143434 T485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 143435 T485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master
[junit4] 2> 143435 T485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\'
[junit4] 2> 143462 T485 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-1377167258374\master\solr.xml
[junit4] 2> 143464 T485 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\solr.xml does not exist, using default configuration
[junit4] 2> 143534 T485 oasc.CoreContainer.<init> New CoreContainer 1707617016
[junit4] 2> 143535 T485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\]
[junit4] 2> 143537 T485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 143537 T485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 143537 T485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 143538 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 143538 T485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 143538 T485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 143538 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 143539 T485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 143539 T485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 143540 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 143554 T549 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\collection1
[junit4] 2> 143555 T549 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\collection1\'
[junit4] 2> 143616 T549 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 143678 T549 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 143678 T549 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 143685 T549 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 143706 T549 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 143708 T549 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 143708 T549 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258374\master\collection1\data\
[junit4] 2> 143708 T549 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 143709 T549 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-1377167258374\master\collection1\data\
[junit4] 2> 143709 T549 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-1377167258374\master\collection1\data\index/
[junit4] 2> 143709 T549 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-1377167258374\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 143709 T549 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-1377167258374\master\collection1\data\index
[junit4] 2> 143710 T549 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=81.6240234375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=11.164015638173343, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 143711 T549 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d28b564 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@424127cf),segFN=segments_1,generation=1}
[junit4] 2> 143711 T549 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 143712 T549 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 143712 T549 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 143713 T549 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 143713 T549 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 143713 T549 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 143713 T549 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 143714 T549 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 143720 T549 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 143722 T549 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 143723 T549 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 143723 T549 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7914529025739787]
[junit4] 2> 143724 T549 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d28b564 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@424127cf),segFN=segments_1,generation=1}
[junit4] 2> 143724 T549 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 143724 T549 oass.SolrIndexSearcher.<init> Opening Searcher@107ad736 main
[junit4] 2> 143725 T549 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 143725 T549 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 143726 T549 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 143726 T550 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@107ad736 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 143726 T485 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 143727 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 143728 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 143749 T485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 143752 T485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63530
[junit4] 2> 143752 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 143752 T485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 143752 T485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave
[junit4] 2> 143754 T485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\'
[junit4] 2> 143769 T485 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-1377167258692\slave\solr.xml
[junit4] 2> 143770 T485 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\solr.xml does not exist, using default configuration
[junit4] 2> 143830 T485 oasc.CoreContainer.<init> New CoreContainer 940212733
[junit4] 2> 143831 T485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\]
[junit4] 2> 143833 T485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 143833 T485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 143833 T485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 143835 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 143835 T485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 143835 T485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 143835 T485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 143836 T485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 143836 T485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 143836 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 143855 T561 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\collection1
[junit4] 2> 143855 T561 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\collection1\'
[junit4] 2> 143917 T561 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 143974 T561 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 143975 T561 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 143982 T561 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 144001 T561 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 144002 T561 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 144003 T561 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377167258692\slave\collection1\data\
[junit4] 2> 144003 T561 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 144004 T561 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-1377167258692\slave\collection1\data\
[junit4] 2> 144004 T561 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-1377167258692\slave\collection1\data\index/
[junit4] 2> 144004 T561 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-1377167258692\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 144004 T561 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-1377167258692\slave\collection1\data\index
[junit4] 2> 144005 T561 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=81.6240234375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=11.164015638173343, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 144006 T561 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f7864c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@592e4ff9),segFN=segments_1,generation=1}
[junit4] 2> 144006 T561 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 144008 T561 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 144008 T561 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 144008 T561 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 144008 T561 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 144009 T561 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 144009 T561 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 144009 T561 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 144011 T561 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 144015 T561 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 144016 T561 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 144016 T561 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7914529025739787]
[junit4] 2> 144017 T561 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f7864c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@592e4ff9),segFN=segments_1,generation=1}
[junit4] 2> 144017 T561 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 144017 T561 oass.SolrIndexSearcher.<init> Opening Searcher@13a8e59b main
[junit4] 2> 144018 T561 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 144018 T561 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 144018 T562 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13a8e59b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 144018 T485 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 144018 T485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 144019 T485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 144036 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=940212733
[junit4] 2> 144038 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38d760bb
[junit4] 2> 144039 T485 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> 144039 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 144041 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 144041 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 144044 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 144045 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 144046 T485 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-1377167258692\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-1377167258692\slave\collection1\data\;done=false>>]
[junit4] 2> 144048 T485 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-1377167258692\slave\collection1\data\
[junit4] 2> 144048 T485 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-1377167258692\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-1377167258692\slave\collection1\data\index;done=false>>]
[junit4] 2> 144049 T485 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-1377167258692\slave\collection1\data\index
[junit4] 2> 144051 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> ASYNC NEW_CORE C64 name=collection1 org.apache.solr.core.SolrCore@3da02d7
[junit4] 2> 144116 T544 C64 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 144117 T544 C64 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 144123 T545 C64 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 144126 T545 C64 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d28b564 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@424127cf),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5d28b564 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@424127cf),segFN=segments_2,generation=2}
[junit4] 2> 144127 T545 C64 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 144129 T545 C64 oass.SolrIndexSearcher.<init> Opening Searcher@41258eb3 main
[junit4] 2> 144130 T545 C64 oasu.DirectUpdateH
[...truncated too long message...]
Searcher [collection1] Registered new searcher Searcher@61cfa835 main{StandardDirectoryReader(segments_2:4:nrt _0(4.5):c492)}
[junit4] 2> 239206 T1163 C116 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 37
[junit4] 2> 239212 T1165 C116 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1
[junit4] 2> 239227 T1166 C116 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={numberToKeep=1&command=backup} status=0 QTime=15
[junit4] 2> 239227 T1174 oash.SnapShooter.createSnapshot Creating backup snapshot...
[junit4] 2> 239420 T1164 C116 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1
[junit4] 2> 240072 T1158 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:63724/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:63724/solr
[junit4] 2> 240429 T1168 C116 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={numberToKeep=1&command=backup} status=0 QTime=2
[junit4] 2> 240429 T1177 oash.SnapShooter.createSnapshot Creating backup snapshot...
[junit4] 2> 240429 T1163 C116 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=3
[junit4] 2> 240636 T1166 C116 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1
[junit4] 2> 240651 T485 oas.SolrTestCaseJ4.tearDown ###Ending doTestBackup
[junit4] 2> 240664 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1660421636
[junit4] 2> 240666 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@71bb7179
[junit4] 2> 240666 T485 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=492,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 240667 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 240667 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 240668 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 240669 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 240670 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 240670 T485 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-1377167351255\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-1377167351255\master\collection1\data\index;done=false>>]
[junit4] 2> 240671 T485 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-1377167351255\master\collection1\data\index
[junit4] 2> 240671 T485 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-1377167351255\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-1377167351255\master\collection1\data\;done=false>>]
[junit4] 2> 240671 T485 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-1377167351255\master\collection1\data\
[junit4] 2> 240672 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 240736 T485 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1404847572
[junit4] 2> 240737 T485 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4367be7c
[junit4] 2> 241072 T1158 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:63724/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:63724/solr
[junit4] 2> 241074 T485 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> 241074 T485 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 241075 T485 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 241075 T485 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 241076 T485 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 241076 T485 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 241076 T485 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-1377167351666\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-1377167351666\slave\collection1\data\;done=false>>]
[junit4] 2> 241076 T485 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-1377167351666\slave\collection1\data\
[junit4] 2> 241078 T485 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-1377167351666\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-1377167351666\slave\collection1\data\index;done=false>>]
[junit4] 2> 241078 T485 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-1377167351666\slave\collection1\data\index
[junit4] 2> 241079 T485 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 241169 T485 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=182), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=182)), sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ar_BH, timezone=America/Rio_Branco
[junit4] 2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_45 (64-bit)/cpus=2,threads=1,free=19874800,total=206045184
[junit4] 2> NOTE: All tests run in this JVM: [TestSchemaVersionResource, TestDocSet, TestElisionMultitermQuery, SearchHandlerTest, ChaosMonkeySafeLeaderTest, ZkNodePropsTest, LukeRequestHandlerTest, SynonymTokenizerTest, HdfsChaosMonkeySafeLeaderTest, TestSerializedLuceneMatchVersion, TestNumberUtils, DefaultValueUpdateProcessorTest, TestCopyFieldCollectionResource, SuggesterWFSTTest, TestNRTOpen, TestReplicationHandler]
[junit4] Completed in 99.77s, 14 tests, 1 failure <<< FAILURES!
[...truncated 974 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: 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:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:450: 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: 324 suites, 1407 tests, 1 failure, 42 ignored (13 assumptions)
Total time: 84 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure