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/18 00:43:58 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_25) - Build #
3156 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3156/
Java: 32bit/jdk1.7.0_25 -client -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sun Aug 18 00:27:57 CEST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sun Aug 18 00:27:57 CEST 2013
at __randomizedtesting.SeedInfo.seed([1461C37A9CFB5BFC:CFCAC3BC99D3324F]: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:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9877 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 2508983 T5868 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4] 2> 2509008 T5868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2509014 T5868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50529
[junit4] 2> 2509014 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2509014 T5868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2509015 T5868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master
[junit4] 2> 2509015 T5868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\'
[junit4] 2> 2509045 T5868 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\solr.xml
[junit4] 2> 2509046 T5868 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\solr.xml does not exist, using default configuration
[junit4] 2> 2509148 T5868 oasc.CoreContainer.<init> New CoreContainer 1343759
[junit4] 2> 2509148 T5868 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\]
[junit4] 2> 2509150 T5868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2509151 T5868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2509151 T5868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2509151 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2509151 T5868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2509151 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2509151 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2509152 T5868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2509152 T5868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2509152 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2509173 T5878 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1
[junit4] 2> 2509173 T5878 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\'
[junit4] 2> 2509232 T5878 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2509308 T5878 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2509309 T5878 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2509317 T5878 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 2509342 T5878 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2509343 T5878 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2509344 T5878 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\
[junit4] 2> 2509344 T5878 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2509344 T5878 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\
[junit4] 2> 2509344 T5878 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\index/
[junit4] 2> 2509344 T5878 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2509345 T5878 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\index
[junit4] 2> 2509346 T5878 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2509346 T5878 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_1,generation=1}
[junit4] 2> 2509346 T5878 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2509347 T5878 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2509347 T5878 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2509348 T5878 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2509348 T5878 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2509348 T5878 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2509348 T5878 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2509349 T5878 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2509358 T5878 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2509361 T5878 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2509361 T5878 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2509361 T5878 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=23.927734375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=2.346481586332164, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7076913860982342
[junit4] 2> 2509362 T5878 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_1,generation=1}
[junit4] 2> 2509362 T5878 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2509362 T5878 oass.SolrIndexSearcher.<init> Opening Searcher@9a823e main
[junit4] 2> 2509363 T5878 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2509363 T5878 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2509364 T5878 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2509364 T5879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9a823e main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2509365 T5868 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2509365 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2509366 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2509384 T5868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2509388 T5868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50532
[junit4] 2> 2509389 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2509389 T5868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2509390 T5868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave
[junit4] 2> 2509390 T5868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\'
[junit4] 2> 2509409 T5868 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\solr.xml
[junit4] 2> 2509413 T5868 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\solr.xml does not exist, using default configuration
[junit4] 2> 2509492 T5868 oasc.CoreContainer.<init> New CoreContainer 6023432
[junit4] 2> 2509492 T5868 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\]
[junit4] 2> 2509494 T5868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2509494 T5868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2509495 T5868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2509495 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2509495 T5868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2509496 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2509496 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2509496 T5868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2509497 T5868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2509497 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2509509 T5890 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1
[junit4] 2> 2509509 T5890 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\'
[junit4] 2> 2509569 T5890 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2509633 T5890 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2509634 T5890 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2509642 T5890 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 2509668 T5890 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2509670 T5890 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2509671 T5890 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\
[junit4] 2> 2509671 T5890 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2509671 T5890 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\
[junit4] 2> 2509671 T5890 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index/
[junit4] 2> 2509671 T5890 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2509672 T5890 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index
[junit4] 2> 2509673 T5890 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2509674 T5890 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_1,generation=1}
[junit4] 2> 2509674 T5890 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2509675 T5890 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2509676 T5890 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2509676 T5890 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2509676 T5890 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2509676 T5890 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2509677 T5890 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2509677 T5890 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2509678 T5890 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2509683 T5890 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2509683 T5890 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2509683 T5890 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=23.927734375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=2.346481586332164, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7076913860982342
[junit4] 2> 2509683 T5890 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_1,generation=1}
[junit4] 2> 2509683 T5890 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2509684 T5890 oass.SolrIndexSearcher.<init> Opening Searcher@f42d39 main
[junit4] 2> 2509685 T5890 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2509685 T5890 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2509685 T5891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f42d39 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2509686 T5868 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2509686 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2509686 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2200 name=collection1 org.apache.solr.core.SolrCore@14e9694
[junit4] 2> 2509701 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2509705 T5874 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 2509716 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2201 name=collection1 org.apache.solr.core.SolrCore@c12ae6
[junit4] 2> 2509717 T5884 C2201 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 2509721 T5876 C2200 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 2509723 T5877 C2200 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2509725 T5877 C2200 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_2,generation=2}
[junit4] 2> 2509725 T5877 C2200 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2509726 T5877 C2200 oass.SolrIndexSearcher.<init> Opening Searcher@b83197 main
[junit4] 2> 2509726 T5877 C2200 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2509727 T5879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b83197 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 2> 2509728 T5877 C2200 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 2> 2509734 T5894 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2509734 T5894 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2509736 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2509736 T5894 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2509737 T5894 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2509737 T5894 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2509743 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=3
[junit4] 2> 2509745 T5894 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 2509746 T5894 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002744985
[junit4] 2> 2509746 T5894 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@43c419 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17d9f85) fullCopy=false
[junit4] 2> 2509750 T5874 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509752 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509755 T5876 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509759 T5877 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509762 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509765 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509769 T5874 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509772 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509775 T5876 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509778 T5877 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2509779 T5894 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2509780 T5894 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2509781 T5894 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2509781 T5894 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2509782 T5894 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2509782 T5894 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2509783 T5894 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_2,generation=2}
[junit4] 2> 2509783 T5894 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2509784 T5894 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2509784 T5894 oass.SolrIndexSearcher.<init> Opening Searcher@150c09 main
[junit4] 2> 2509785 T5891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@150c09 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 2> 2509785 T5894 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002744985 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002744985;done=true>>]
[junit4] 2> 2509786 T5894 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002744985
[junit4] 2> 2509786 T5885 C2201 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:50529/solr/replication&command=fetchindex&wait=true} status=0 QTime=53
[junit4] 2> 2509793 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2509793 T5886 C2201 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 2509797 T5873 C2200 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 2509800 T5874 C2200 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2509802 T5874 C2200 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_3,generation=3}
[junit4] 2> 2509802 T5874 C2200 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2509803 T5874 C2200 oass.SolrIndexSearcher.<init> Opening Searcher@88be71 main
[junit4] 2> 2509803 T5874 C2200 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2509804 T5879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@88be71 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 2509804 T5874 C2200 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 2509806 T5897 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2509806 T5897 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2509808 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2509809 T5897 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 2509809 T5897 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 2509809 T5897 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2509812 T5876 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1
[junit4] 2> 2509813 T5897 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 19
[junit4] 2> 2509813 T5897 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745053
[junit4] 2> 2509813 T5897 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@d6e3c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ff7e10) fullCopy=false
[junit4] 2> 2509816 T5877 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509820 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509821 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tim because it already exists
[junit4] 2> 2509821 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 2509823 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509824 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfs because it already exists
[junit4] 2> 2509827 T5874 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509828 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tip because it already exists
[junit4] 2> 2509831 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509834 T5876 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509836 T5877 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509837 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.frq because it already exists
[junit4] 2> 2509840 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509843 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2509844 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfe because it already exists
[junit4] 2> 2509844 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 2509844 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2509845 T5897 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 2509847 T5874 C2200 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> 2509848 T5897 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2509850 T5897 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2509850 T5897 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2509850 T5897 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2509852 T5897 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2509852 T5897 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2509854 T5897 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_3,generation=3}
[junit4] 2> 2509855 T5897 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2509855 T5897 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2509856 T5897 oass.SolrIndexSearcher.<init> Opening Searcher@184dc54 main
[junit4] 2> 2509857 T5891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@184dc54 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 2509857 T5897 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745053 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745053;done=true>>]
[junit4] 2> 2509857 T5897 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745053
[junit4] 2> 2509858 T5888 C2201 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:50529/solr/replication&command=fetchindex&wait=true} status=0 QTime=53
[junit4] 2> 2509863 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2509863 T5889 C2201 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4] 2> 2509867 T5876 C2200 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 2509869 T5877 C2200 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2509870 T5877 C2200 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1158257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c7624),segFN=segments_4,generation=4}
[junit4] 2> 2509871 T5877 C2200 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2509871 T5877 C2200 oass.SolrIndexSearcher.<init> Opening Searcher@1129824 main
[junit4] 2> 2509872 T5877 C2200 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2509872 T5879 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1129824 main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 2509873 T5877 C2200 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 2509874 T5899 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2509875 T5899 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2509877 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2509877 T5899 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4] 2> 2509877 T5899 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 2509877 T5899 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2509880 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=1
[junit4] 2> 2509880 T5899 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 28
[junit4] 2> 2509881 T5899 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745121
[junit4] 2> 2509881 T5899 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@19342fe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9ee39d) fullCopy=false
[junit4] 2> 2509883 T5874 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509884 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.tim because it already exists
[junit4] 2> 2509885 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4] 2> 2509886 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.tip because it already exists
[junit4] 2> 2509888 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509891 T5876 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509891 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.frq because it already exists
[junit4] 2> 2509891 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2509894 T5877 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509897 T5872 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509898 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_nrm.cfs because it already exists
[junit4] 2> 2509898 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tim because it already exists
[junit4] 2> 2509899 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 2509899 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.frq because it already exists
[junit4] 2> 2509901 T5873 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509903 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfs because it already exists
[junit4] 2> 2509905 T5874 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509905 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1_nrm.cfe because it already exists
[junit4] 2> 2509905 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tip because it already exists
[junit4] 2> 2509908 T5875 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509909 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4] 2> 2509910 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4] 2> 2509913 T5876 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509914 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4] 2> 2509914 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfe because it already exists
[junit4] 2> 2509914 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 2509915 T5899 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 2509917 T5877 C2200 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2509919 T5899 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2509920 T5899 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2509920 T5899 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2509920 T5899 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2509921 T5899 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2509921 T5899 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2509922 T5899 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c181e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a48bf3),segFN=segments_4,generation=4}
[junit4] 2> 2509923 T5899 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2509924 T5899 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2509925 T5899 oass.SolrIndexSearcher.<init> Opening Searcher@f4c4df main
[junit4] 2> 2509925 T5891 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f4c4df main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 2509926 T5899 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745121 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745121;done=true>>]
[junit4] 2> 2509926 T5899 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index.20130818002745121
[junit4] 2> 2509926 T5884 C2201 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:50529/solr/replication&command=fetchindex&wait=true} status=0 QTime=52
[junit4] 2> 2509947 T5868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2509952 T5868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50539
[junit4] 2> 2509954 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2509954 T5868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2509955 T5868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater
[junit4] 2> 2509955 T5868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\'
[junit4] 2> 2509984 T5868 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\solr.xml
[junit4] 2> 2509985 T5868 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\solr.xml does not exist, using default configuration
[junit4] 2> 2510074 T5868 oasc.CoreContainer.<init> New CoreContainer 15400201
[junit4] 2> 2510075 T5868 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\]
[junit4] 2> 2510077 T5868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2510077 T5868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2510077 T5868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2510078 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2510078 T5868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2510079 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2510079 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2510079 T5868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2510080 T5868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2510080 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2510097 T5910 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1
[junit4] 2> 2510097 T5910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\'
[junit4] 2> 2510164 T5910 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2510239 T5910 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2510240 T5910 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2510246 T5910 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 2510269 T5910 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2510270 T5910 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2510272 T5910 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\
[junit4] 2> 2510272 T5910 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2510272 T5910 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\
[junit4] 2> 2510272 T5910 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\index/
[junit4] 2> 2510272 T5910 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2510273 T5910 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\index
[junit4] 2> 2510274 T5910 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2510274 T5910 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a3e863 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ff9bf),segFN=segments_1,generation=1}
[junit4] 2> 2510275 T5910 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2510276 T5910 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2510276 T5910 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2510276 T5910 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2510276 T5910 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2510276 T5910 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2510277 T5910 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2510277 T5910 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2510285 T5910 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2510289 T5910 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2510289 T5910 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2510289 T5910 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=23.927734375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=2.346481586332164, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7076913860982342
[junit4] 2> 2510290 T5910 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a3e863 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ff9bf),segFN=segments_1,generation=1}
[junit4] 2> 2510290 T5910 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2510290 T5910 oass.SolrIndexSearcher.<init> Opening Searcher@157c568 main
[junit4] 2> 2510290 T5910 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2510290 T5910 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2510291 T5910 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 2510291 T5910 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2510291 T5910 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2510291 T5911 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@157c568 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2510292 T5868 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2510292 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2510292 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2202 name=collection1 org.apache.solr.core.SolrCore@14e9694
[junit4] 2> 2510310 T5873 C2202 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2203 name=collection1 org.apache.solr.core.SolrCore@142ad4b
[junit4] 2> 2510310 T5904 C2203 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 2510323 T5868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15400201
[junit4] 2> 2510324 T5868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@142ad4b
[junit4] 2> 2510324 T5868 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> 2510324 T5868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2510325 T5868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2510325 T5868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2510326 T5868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2510327 T5868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2510327 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\;done=false>>]
[junit4] 2> 2510327 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\
[junit4] 2> 2510327 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\index;done=false>>]
[junit4] 2> 2510328 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465167\repeater\collection1\data\index
[junit4] 2> 2510329 T5868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2510398 T5868 oas.SolrTestCaseJ4.tearDown ###Ending doTestDetails
[junit4] 2> 2510409 T5868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1343759
[junit4] 2> 2510410 T5868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14e9694
[junit4] 2> 2510410 T5868 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2510410 T5868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2510410 T5868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2510411 T5868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2510413 T5868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2510413 T5868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2510413 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\;done=false>>]
[junit4] 2> 2510414 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\
[junit4] 2> 2510414 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\index;done=false>>]
[junit4] 2> 2510414 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464225\master\collection1\data\index
[junit4] 2> 2510415 T5868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2510478 T5868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6023432
[junit4] 2> 2510480 T5868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c12ae6
[junit4] 2> 2510480 T5868 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> 2510481 T5868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2510481 T5868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2510482 T5868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2510483 T5868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2510483 T5868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2510485 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index;done=false>>]
[junit4] 2> 2510485 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\index
[junit4] 2> 2510485 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\;done=false>>]
[junit4] 2> 2510485 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778464611\slave\collection1\data\
[junit4] 2> 2510486 T5868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2510581 T5868 oas.SolrTestCaseJ4.setUp ###Starting doTestBackup
[junit4] 2> 2510596 T5868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2510600 T5868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50543
[junit4] 2> 2510601 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2510601 T5868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2510601 T5868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master
[junit4] 2> 2510602 T5868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\'
[junit4] 2> 2510631 T5868 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\solr.xml
[junit4] 2> 2510632 T5868 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\solr.xml does not exist, using default configuration
[junit4] 2> 2510719 T5868 oasc.CoreContainer.<init> New CoreContainer 16050257
[junit4] 2> 2510719 T5868 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\]
[junit4] 2> 2510720 T5868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2510721 T5868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2510721 T5868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2510721 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2510722 T5868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2510722 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2510723 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2510723 T5868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2510724 T5868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2510724 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2510741 T5922 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1
[junit4] 2> 2510741 T5922 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\'
[junit4] 2> 2510803 T5922 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2510879 T5922 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2510879 T5922 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2510939 T5922 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 2510965 T5922 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2510967 T5922 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2510968 T5922 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\data\
[junit4] 2> 2510968 T5922 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2510968 T5922 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\data\
[junit4] 2> 2510969 T5922 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\data\index/
[junit4] 2> 2510969 T5922 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2510969 T5922 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778465821\master\collection1\data\index
[junit4] 2> 2510970 T5922 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2510971 T5922 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4fd872 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1313bf7),segFN=segments_1,generation=1}
[junit4] 2> 2510971 T5922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2510972 T5922 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2510972 T5922 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2510973 T5922 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2510973 T5922 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2510973 T5922 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2510973 T5922 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2510974 T5922 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2510984 T5922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2510988 T5922 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2510988 T5922 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2510988 T5922 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=23.927734375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=2.346481586332164, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7076913860982342
[junit4] 2> 2510989 T5922 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4fd872 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1313bf7),segFN=segments_1,generation=1}
[junit4] 2> 2510989 T5922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2510989 T5922 oass.SolrIndexSearcher.<init> Opening Searcher@46a59f main
[junit4] 2> 2510990 T5922 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2510990 T5922 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2510990 T5922 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2510990 T5923 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@46a59f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2510991 T5868 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2510992 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2510992 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2511010 T5868 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2511015 T5868 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50546
[junit4] 2> 2511016 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2511016 T5868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2511017 T5868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave
[junit4] 2> 2511017 T5868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\'
[junit4] 2> 2511034 T5868 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\solr.xml
[junit4] 2> 2511036 T5868 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\solr.xml does not exist, using default configuration
[junit4] 2> 2511135 T5868 oasc.CoreContainer.<init> New CoreContainer 23315030
[junit4] 2> 2511135 T5868 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\]
[junit4] 2> 2511138 T5868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2511138 T5868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2511139 T5868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2511139 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2511139 T5868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2511140 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2511140 T5868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2511141 T5868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2511141 T5868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2511142 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2511156 T5934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1
[junit4] 2> 2511156 T5934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\'
[junit4] 2> 2511212 T5934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2511271 T5934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2511272 T5934 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2511278 T5934 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 2511301 T5934 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2511302 T5934 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2511302 T5934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\data\
[junit4] 2> 2511302 T5934 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2511303 T5934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\data\
[junit4] 2> 2511303 T5934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\data\index/
[junit4] 2> 2511303 T5934 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2511304 T5934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778466237\slave\collection1\data\index
[junit4] 2> 2511304 T5934 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2511305 T5934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@29864d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55384c),segFN=segments_1,generation=1}
[junit4] 2> 2511305 T5934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2511306 T5934 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2511306 T5934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2511307 T5934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2511307 T5934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2511307 T5934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2511307 T5934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2511308 T5934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2511310 T5934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2511313 T5934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2511314 T5934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2511314 T5934 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=23.927734375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=2.346481586332164, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7076913860982342
[junit4] 2> 2511315 T5934 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@29864d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55384c),segFN=segments_1,generation=1}
[junit4] 2> 2511315 T5934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2511315 T5934 oass.SolrIndexSearcher.<init> Opening Searcher@ba34a main
[junit4] 2> 2511316 T5934 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2511316 T5934 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2511316 T5935 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ba34a main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2511316 T5868 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2511316 T5868 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2511317 T5868 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2511335 T5868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16050257
[junit4]
[...truncated too long message...]
c.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572428\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572428\master\collection1\data\;done=false>>]
[junit4] 2> 2622239 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572428\master\collection1\data\
[junit4] 2> 2622239 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572428\master\collection1\data\index.20130818002936731 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572428\master\collection1\data\index.20130818002936731;done=false>>]
[junit4] 2> 2622240 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572428\master\collection1\data\index.20130818002936731
[junit4] 2> 2622240 T5868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2622305 T5868 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=22098655
[junit4] 2> 2622308 T5868 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@32d0ac
[junit4] 2> 2622308 T5868 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 2622308 T5868 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2622309 T5868 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2622309 T5868 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2622310 T5868 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2622311 T5868 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2622312 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572945\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572945\slave\collection1\data\;done=false>>]
[junit4] 2> 2622312 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572945\slave\collection1\data\
[junit4] 2> 2622312 T5868 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572945\slave\collection1\data\index.20130818002935636 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572945\slave\collection1\data\index.20130818002935636;done=false>>]
[junit4] 2> 2622312 T5868 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376778572945\slave\collection1\data\index.20130818002935636
[junit4] 2> 2622313 T5868 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2622399 T5868 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=es_CO, timezone=Europe/Oslo
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_25 (32-bit)/cpus=2,threads=1,free=158560272,total=370540544
[junit4] 2> NOTE: All tests run in this JVM: [SchemaVersionSpecificBehaviorTest, TestSuggestSpellingConverter, SliceStateUpdateTest, TestSweetSpotSimilarityFactory, TestSystemIdResolver, NotRequiredUniqueKeyTest, RequestHandlersTest, TestRecovery, TestSolrXMLSerializer, TestLFUCache, TestStressReorder, TestLazyCores, BinaryUpdateRequestHandlerTest, TestCloudManagedSchema, DebugComponentTest, TestPerFieldSimilarity, SpatialFilterTest, QueryEqualityTest, TimeZoneUtilsTest, FieldMutatingUpdateProcessorTest, TestFiltering, PrimitiveFieldTypeTest, BadCopyFieldTest, TestWriterPerf, TestCollationField, SolrPluginUtilsTest, BasicDistributedZkTest, TestReversedWildcardFilterFactory, FileUtilsTest, StandardRequestHandlerTest, ConvertedLegacyTest, SpellCheckComponentTest, ChaosMonkeySafeLeaderTest, SolrIndexConfigTest, TestRTGBase, TestNRTOpen, TestValueSourceCache, ClusterStateUpdateTest, SystemInfoHandlerTest, ZkCLITest, SolrInfoMBeanTest, AssignTest, DistributedSpellCheckComponentTest, OpenCloseCoreStressTest, UUIDFieldTest, CoreAdminCreateDiscoverTest, AddBlockUpdateTest, HdfsBasicDistributedZk2Test, HdfsCollectionsAPIDistributedZkTest, LeaderElectionTest, TestMultiCoreConfBootstrap, TestSchemaVersionResource, TestUtils, TestTrie, CurrencyFieldXmlFileTest, HdfsUnloadDistributedZkTest, LegacyHTMLStripCharFilterTest, TestExtendedDismaxParser, BJQParserTest, TestFieldResource, TestSolrQueryParser, TestFastLRUCache, PeerSyncTest, RAMDirectoryFactoryTest, TestImplicitCoreProperties, StressHdfsTest, TestDistributedGrouping, TestXIncludeConfig, MBeansHandlerTest, TestSolrQueryParserDefaultOperatorResource, TestAtomicUpdateErrorCases, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, SimpleFacetsTest, SpellCheckCollatorTest, PingRequestHandlerTest, TestAnalyzedSuggestions, CachingDirectoryFactoryTest, TestFuzzyAnalyzedSuggestions, OpenExchangeRatesOrgProviderTest, TestCodecSupport, TestPartialUpdateDeduplication, QueryElevationComponentTest, TestRecoveryHdfs, RegexBoostProcessorTest, AutoCommitTest, TestFaceting, HdfsChaosMonkeySafeLeaderTest, TestRandomDVFaceting, HdfsDirectoryTest, HdfsBasicDistributedZkTest, OverseerCollectionProcessorTest, TestStressLucene, TestFunctionQuery, TestFastWriter, PrimUtilsTest, SuggesterTSTTest, AnalysisAfterCoreReloadTest, TestSerializedLuceneMatchVersion, NumericFieldsTest, TestUpdate, TestGroupingSearch, URLClassifyProcessorTest, TestFieldTypeCollectionResource, QueryParsingTest, BadComponentTest, CSVRequestHandlerTest, CustomCollectionTest, TestPseudoReturnFields, NoCacheHeaderTest, SimplePostToolTest, InfoHandlerTest, ShardRoutingTest, TestConfig, HighlighterConfigTest, ChaosMonkeyNothingIsSafeTest, TestNumberUtils, TestCSVLoader, TestLuceneMatchVersion, SliceStateTest, DocValuesMultiTest, DirectUpdateHandlerTest, TestSchemaResource, CacheHeaderTest, TestCloudManagedSchemaAddField, PreAnalyzedUpdateProcessorTest, TestQueryTypes, CoreMergeIndexesAdminHandlerTest, DateMathParserTest, XmlUpdateRequestHandlerTest, SolrRequestParserTest, TestFieldCollectionResource, TestDefaultSimilarityFactory, BlockCacheTest, IndexSchemaTest, HdfsLockFactoryTest, TestManagedSchema, TestDynamicFieldResource, SpellingQueryConverterTest, ExternalFileFieldSortTest, SuggesterTest, TestSchemaSimilarityResource, ShardRoutingCustomTest, LeaderElectionIntegrationTest, TestIBSimilarityFactory, TestCSVResponseWriter, TestJmxMonitoredMap, TestCoreContainer, TestSearchPerf, TestJoin, OverseerTest, DirectSolrSpellCheckerTest, ZkControllerTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler]
[junit4] Completed in 113.47s, 14 tests, 1 failure <<< FAILURES!
[...truncated 484 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:389: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:369: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:450: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 322 suites, 1372 tests, 1 failure, 39 ignored (11 assumptions)
Total time: 82 minutes 44 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_25 -client -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure