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/29 22:43:46 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0-ea-b102) - Build
# 7226 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/7226/
Java: 64bit/jdk1.8.0-ea-b102 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Fri Aug 30 06:38:40 ChST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Fri Aug 30 06:38:40 ChST 2013
at __randomizedtesting.SeedInfo.seed([2F66B581C6103A55:F4CDB547C33853E6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1514)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:818)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9987 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 610431 T1960 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 610435 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 610437 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36086
[junit4] 2> 610438 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 610438 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 610438 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master
[junit4] 2> 610439 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/'
[junit4] 2> 610448 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/solr.xml
[junit4] 2> 610454 T1960 oasc.CoreContainer.<init> New CoreContainer 1360945462
[junit4] 2> 610454 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/]
[junit4] 2> 610455 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 610455 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 610455 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 610455 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 610456 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 610456 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 610456 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 610456 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 610456 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 610457 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 610459 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 610459 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 610461 T1970 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1
[junit4] 2> 610461 T1970 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/'
[junit4] 2> 610472 T1970 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 610477 T1970 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 610477 T1970 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 610479 T1970 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 610481 T1970 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 610481 T1970 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 610481 T1970 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/
[junit4] 2> 610481 T1970 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 610482 T1970 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data
[junit4] 2> 610482 T1970 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index/
[junit4] 2> 610482 T1970 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 610482 T1970 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index
[junit4] 2> 610482 T1970 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=59.2275390625, floorSegmentMB=2.0791015625, forceMergeDeletesPctAllowed=26.33026420871102, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 610483 T1970 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6187cacb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46c8bc4e),segFN=segments_1,generation=1}
[junit4] 2> 610483 T1970 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610484 T1970 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 610484 T1970 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 610484 T1970 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 610484 T1970 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 610484 T1970 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 610484 T1970 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 610484 T1970 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 610486 T1970 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 610486 T1970 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 610487 T1970 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 610487 T1970 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@483e1179
[junit4] 2> 610537 T1970 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6187cacb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46c8bc4e),segFN=segments_1,generation=1}
[junit4] 2> 610538 T1970 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610538 T1970 oass.SolrIndexSearcher.<init> Opening Searcher@1121d21b main
[junit4] 2> 610538 T1970 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 610538 T1970 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 610538 T1970 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 610538 T1971 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1121d21b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 610539 T1960 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 610539 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 610540 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 610545 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 610547 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46191
[junit4] 2> 610549 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 610549 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 610549 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave
[junit4] 2> 610549 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/'
[junit4] 2> 610556 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/solr.xml
[junit4] 2> 610562 T1960 oasc.CoreContainer.<init> New CoreContainer 687028102
[junit4] 2> 610562 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/]
[junit4] 2> 610563 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 610563 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 610563 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 610563 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 610564 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 610564 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 610564 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 610564 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 610564 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 610565 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 610566 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 610567 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 610568 T1982 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1
[junit4] 2> 610568 T1982 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/'
[junit4] 2> 610578 T1982 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 610583 T1982 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 610583 T1982 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 610585 T1982 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 610588 T1982 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 610589 T1982 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 610589 T1982 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/
[junit4] 2> 610589 T1982 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 610590 T1982 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data
[junit4] 2> 610590 T1982 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index/
[junit4] 2> 610590 T1982 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 610590 T1982 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index
[junit4] 2> 610591 T1982 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=59.2275390625, floorSegmentMB=2.0791015625, forceMergeDeletesPctAllowed=26.33026420871102, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 610591 T1982 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5393107 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@495758c1),segFN=segments_1,generation=1}
[junit4] 2> 610592 T1982 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610592 T1982 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 610593 T1982 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 610593 T1982 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 610593 T1982 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 610593 T1982 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 610593 T1982 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 610594 T1982 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 610595 T1982 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 610595 T1982 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 610596 T1982 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 610596 T1982 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@596f3667
[junit4] 2> 610646 T1982 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5393107 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@495758c1),segFN=segments_1,generation=1}
[junit4] 2> 610647 T1982 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610647 T1982 oass.SolrIndexSearcher.<init> Opening Searcher@74142063 main
[junit4] 2> 610649 T1982 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 610649 T1982 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 610649 T1983 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74142063 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 610649 T1960 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 610650 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 610650 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 610673 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=687028102
[junit4] 2> 610674 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d1f1616
[junit4] 2> 610675 T1960 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> 610675 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 610675 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 610676 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 610677 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 610677 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 610677 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index;done=false>>]
[junit4] 2> 610678 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index
[junit4] 2> 610678 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data;done=false>>]
[junit4] 2> 610678 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data
[junit4] 2> 610679 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 610752 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1360945462
[junit4] 2> 610753 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@69d4d530
[junit4] 2> 610753 T1960 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> 610753 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 610754 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 610754 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 610755 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 610755 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 610755 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index;done=false>>]
[junit4] 2> 610755 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index
[junit4] 2> 610755 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data;done=false>>]
[junit4] 2> 610756 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data
[junit4] 2> 610756 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 610808 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 610809 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58727
[junit4] 2> 610810 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 610810 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 610811 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master
[junit4] 2> 610811 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/'
[junit4] 2> 610819 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/solr.xml
[junit4] 2> 610824 T1960 oasc.CoreContainer.<init> New CoreContainer 1834203822
[junit4] 2> 610825 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/]
[junit4] 2> 610825 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 610825 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 610826 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 610826 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 610826 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 610826 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 610826 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 610827 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 610827 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 610827 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 610829 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 610829 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 610831 T1995 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1
[junit4] 2> 610831 T1995 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/'
[junit4] 2> 610842 T1995 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 610856 T1995 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 610856 T1995 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 610859 T1995 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 610861 T1995 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 610861 T1995 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 610862 T1995 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/
[junit4] 2> 610862 T1995 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 610862 T1995 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data
[junit4] 2> 610863 T1995 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index/
[junit4] 2> 610863 T1995 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 610863 T1995 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index
[junit4] 2> 610864 T1995 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=59.2275390625, floorSegmentMB=2.0791015625, forceMergeDeletesPctAllowed=26.33026420871102, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 610864 T1995 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_1,generation=1}
[junit4] 2> 610864 T1995 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610865 T1995 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 610866 T1995 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 610866 T1995 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 610866 T1995 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 610866 T1995 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 610866 T1995 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 610867 T1995 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 610868 T1995 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 610868 T1995 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 610868 T1995 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 610869 T1995 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@19c8bc42
[junit4] 2> 610919 T1995 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_1,generation=1}
[junit4] 2> 610919 T1995 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610920 T1995 oass.SolrIndexSearcher.<init> Opening Searcher@17e94da8 main
[junit4] 2> 610920 T1995 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 610920 T1995 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 610920 T1996 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17e94da8 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 610920 T1995 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 610921 T1960 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 610921 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 610921 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C465 name=collection1 org.apache.solr.core.SolrCore@17813bff
[junit4] 2> 610925 T1991 C465 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 610926 T1991 C465 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 610928 T1992 C465 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 610928 T1992 C465 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_2,generation=2}
[junit4] 2> 610929 T1992 C465 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 610929 T1992 C465 oass.SolrIndexSearcher.<init> Opening Searcher@4e19d6aa main
[junit4] 2> 610929 T1992 C465 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 610929 T1996 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e19d6aa main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 610930 T1992 C465 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4] 2> 610932 T1993 C465 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 610933 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 610934 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:32796
[junit4] 2> 610935 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 610935 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 610935 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave
[junit4] 2> 610936 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/'
[junit4] 2> 610943 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/solr.xml
[junit4] 2> 610948 T1960 oasc.CoreContainer.<init> New CoreContainer 670466776
[junit4] 2> 610948 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/]
[junit4] 2> 610949 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 610949 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 610949 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 610949 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 610949 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 610950 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 610950 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 610950 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 610950 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 610950 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 610953 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 610953 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 610954 T2007 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1
[junit4] 2> 610954 T2007 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/'
[junit4] 2> 610965 T2007 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 610970 T2007 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 610971 T2007 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 610973 T2007 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 610975 T2007 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 610975 T2007 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 610975 T2007 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/
[junit4] 2> 610975 T2007 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 610976 T2007 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data
[junit4] 2> 610976 T2007 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index/
[junit4] 2> 610976 T2007 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 610977 T2007 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index
[junit4] 2> 610977 T2007 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=59.2275390625, floorSegmentMB=2.0791015625, forceMergeDeletesPctAllowed=26.33026420871102, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 610977 T2007 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d729e8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa7a02),segFN=segments_1,generation=1}
[junit4] 2> 610978 T2007 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 610978 T2007 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 610978 T2007 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 610978 T2007 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 610978 T2007 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 610979 T2007 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 610979 T2007 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 610979 T2007 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 610980 T2007 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 610980 T2007 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 610980 T2007 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 610981 T2007 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6a3c7b1d
[junit4] 2> 611031 T2007 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d729e8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa7a02),segFN=segments_1,generation=1}
[junit4] 2> 611031 T2007 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 611031 T2007 oass.SolrIndexSearcher.<init> Opening Searcher@52b0bc36 main
[junit4] 2> 611032 T2007 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 611032 T2007 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 611032 T2008 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52b0bc36 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 611032 T1960 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 611033 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 611033 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C466 name=collection1 org.apache.solr.core.SolrCore@99c4c36
[junit4] 2> 611036 T2003 C466 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> 611038 T1994 C465 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 611040 T1991 C465 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 611041 T1992 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1
[junit4] 2> 611042 T1992 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/'
[junit4] 2> 611058 T1992 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 611064 T1992 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 611065 T1992 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 611067 T1992 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 611069 T1992 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 611069 T1992 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/
[junit4] 2> 611069 T1992 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 611070 T1992 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index/
[junit4] 2> 611070 T1992 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 611070 T1992 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 611071 T1992 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 611071 T1992 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 611071 T1992 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 611071 T1992 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 611071 T1992 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 611072 T1992 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 611073 T1992 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 611073 T1992 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 611073 T1992 oass.SolrIndexSearcher.<init> Opening Searcher@5e2375da main
[junit4] 2> 611074 T1992 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 611074 T1992 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 611074 T1992 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 611074 T1992 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 611074 T1992 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 611074 T2011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5e2375da main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 611075 T1992 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=55.1064453125, floorSegmentMB=1.47265625, forceMergeDeletesPctAllowed=11.275384653404988, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3452932666811702
[junit4] 2> 611075 T1992 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_2,generation=2}
[junit4] 2> 611076 T1992 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 611076 T1992 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 611076 T1992 oass.SolrIndexSearcher.<init> Opening Searcher@760e55db main
[junit4] 2> 611076 T2011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@760e55db main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 611076 T1992 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 611076 T1992 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@17813bff
[junit4] 2> 611077 T1992 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 611077 T1992 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 611077 T1992 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=36
[junit4] 2> ASYNC NEW_CORE C467 name=collection1 org.apache.solr.core.SolrCore@6469711d
[junit4] 2> 611079 T1993 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 611081 T1994 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 611083 T1991 C467 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 611085 T1992 C467 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 611086 T1993 C467 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 611087 T1993 C467 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@636fc33 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c7457e6),segFN=segments_3,generation=3}
[junit4] 2> 611088 T1993 C467 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 611088 T1993 C467 oass.SolrIndexSearcher.<init> Opening Searcher@438a0fdf main
[junit4] 2> 611089 T1993 C467 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 611089 T2011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@438a0fdf main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):c2)}
[junit4] 2> 611089 T1993 C467 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4] 2> 611091 T1994 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> 611092 T2004 C466 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> 611093 T1960 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 611121 T1991 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 611121 T2009 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 611121 T2009 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 611121 T2009 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 611124 T1992 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=2
[junit4] 2> 611124 T2009 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 611125 T2009 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index.20130830063839004
[junit4] 2> 611125 T2009 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@6263e37 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@722db00a) fullCopy=false
[junit4] 2> 611127 T1993 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 611130 T1994 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 611132 T1991 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 611134 T1992 C467 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 611134 T2009 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 611135 T2009 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 611135 T2009 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 611135 T2009 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 611136 T2009 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 611136 T2009 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=50.8427734375, floorSegmentMB=1.6328125, forceMergeDeletesPctAllowed=9.198497500543883, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 611137 T2009 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d729e8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa7a02),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d729e8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa7a02),segFN=segments_3,generation=3}
[junit4] 2> 611137 T2009 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 611137 T2009 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 611138 T2009 oass.SolrIndexSearcher.<init> Opening Searcher@9dc14a6 main
[junit4] 2> 611138 T2008 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9dc14a6 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):c2)}
[junit4] 2> 611138 T2009 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index.20130830063839004 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index.20130830063839004;done=true>>]
[junit4] 2> 611139 T2009 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index.20130830063839004
[junit4] 2> 611195 T2005 C466 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 611195 T1960 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 611216 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1834203822
[junit4] 2> 611217 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6469711d
[junit4] 2> 611217 T1960 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 611218 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 611218 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 611218 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 611219 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 611219 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 611220 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data;done=false>>]
[junit4] 2> 611220 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data
[junit4] 2> 611220 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index;done=false>>]
[junit4] 2> 611220 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718311/master/collection1/data/index
[junit4] 2> 611221 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 611293 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=670466776
[junit4] 2> 611293 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@99c4c36
[junit4] 2> 611294 T1960 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> 611294 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 611294 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 611294 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 611295 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 611295 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 611296 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data;done=false>>]
[junit4] 2> 611296 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data
[junit4] 2> 611296 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index;done=false>>]
[junit4] 2> 611296 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808718422/slave/collection1/data/index
[junit4] 2> 611297 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 611353 T1960 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 611356 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 611357 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58804
[junit4] 2> 611357 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 611357 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 611358 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master
[junit4] 2> 611358 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/'
[junit4] 2> 611367 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/solr.xml
[junit4] 2> 611373 T1960 oasc.CoreContainer.<init> New CoreContainer 735667301
[junit4] 2> 611373 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/]
[junit4] 2> 611374 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 611374 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 611374 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 611374 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 611374 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 611375 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 611375 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 611375 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 611375 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 611375 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 611378 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 611378 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 611382 T2022 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1
[junit4] 2> 611382 T2022 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/'
[junit4] 2> 611393 T2022 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 611405 T2022 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 611405 T2022 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 611407 T2022 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 611410 T2022 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 611410 T2022 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 611410 T2022 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/data/
[junit4] 2> 611410 T2022 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 611411 T2022 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/data
[junit4] 2> 611411 T2022 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/data/index/
[junit4] 2> 611411 T2022 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 611412 T2022 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719233/master/collection1/data/index
[junit4] 2> 611412 T2022 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=59.2275390625, floorSegmentMB=2.0791015625, forceMergeDeletesPctAllowed=26.33026420871102, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 611412 T2022 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@82ae929 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35bae2c6),segFN=segments_1,generation=1}
[junit4] 2> 611412 T2022 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 611413 T2022 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 611414 T2022 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 611414 T2022 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 611414 T2022 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 611414 T2022 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 611414 T2022 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 611414 T2022 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 611417 T2022 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 611417 T2022 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 611417 T2022 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 611418 T2022 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@36fbb698
[junit4] 2> 611468 T2022 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@82ae929 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35bae2c6),segFN=segments_1,generation=1}
[junit4] 2> 611468 T2022 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 611469 T2022 oass.SolrIndexSearcher.<init> Opening Searcher@3ebae8a8 main
[junit4] 2> 611469 T2022 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 611469 T2022 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 611469 T2023 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ebae8a8 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 611469 T2022 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 611470 T1960 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 611470 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 611470 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 611474 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 611476 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59973
[junit4] 2> 611476 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 611476 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 611477 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave
[junit4] 2> 611477 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/'
[junit4] 2> 611483 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/solr.xml
[junit4] 2> 611488 T1960 oasc.CoreContainer.<init> New CoreContainer 1382372274
[junit4] 2> 611488 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/]
[junit4] 2> 611489 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 611489 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 611489 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 611489 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 611489 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 611490 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 611490 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 611490 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 611490 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 611490 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 611492 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 611492 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 611494 T2034 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1
[junit4] 2> 611494 T2034 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/'
[junit4] 2> 611505 T2034 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 611510 T2034 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 611510 T2034 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 611512 T2034 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 611514 T2034 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 611514 T2034 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 611514 T2034 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/
[junit4] 2> 611514 T2034 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 611515 T2034 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data
[junit4] 2> 611515 T2034 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/index/
[junit4] 2> 611515 T2034 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 611515 T2034 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/index
[junit4] 2> 611515 T2034 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=59.2275390625, floorSegmentMB=2.0791015625, forceMergeDeletesPctAllowed=26.33026420871102, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 611516 T2034 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30fa2744 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@357cf609),segFN=segments_1,generation=1}
[junit4] 2> 611516 T2034 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 611517 T2034 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 611517 T2034 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 611517 T2034 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 611517 T2034 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 611517 T2034 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 611517 T2034 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 611517 T2034 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 611518 T2034 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 611519 T2034 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 611519 T2034 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 611519 T2034 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@44036be9
[junit4] 2> 611570 T2034 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30fa2744 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@357cf609),segFN=segments_1,generation=1}
[junit4] 2> 611570 T2034 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 611570 T2034 oass.SolrIndexSearcher.<init> Opening Searcher@534a0c40 main
[junit4] 2> 611571 T2034 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 611571 T2035 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@534a0c40 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 611571 T2034 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 611572 T1960 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 611572 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 611573 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 611596 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1382372274
[junit4] 2> 611596 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2df1a4f0
[junit4] 2> 611597 T1960 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> 611597 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 611597 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 611598 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 611598 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 611598 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 611599 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data;done=false>>]
[junit4] 2> 611599 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data
[junit4] 2> 611599 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/index;done=false>>]
[junit4] 2> 611599 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/data/index
[junit4] 2> 611600 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 611652 T1960 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 611653 T1960 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:41497
[junit4] 2> 611653 T1960 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 611654 T1960 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 611654 T1960 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave
[junit4] 2> 611654 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/'
[junit4] 2> 611662 T1960 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/solr.xml
[junit4] 2> 611667 T1960 oasc.CoreContainer.<init> New CoreContainer 1500112406
[junit4] 2> 611667 T1960 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/]
[junit4] 2> 611668 T1960 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 611668 T1960 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 611668 T1960 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 611668 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 611668 T1960 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 611669 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 611669 T1960 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 611669 T1960 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 611669 T1960 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 611669 T1960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 611671 T1960 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 611672 T1960 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 611673 T2047 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1
[junit4] 2> 611673 T2047 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808719352/slave/collection1/'
[junit4] 2> 611687 T2047 oasc.SolrConfig.<init>
[...truncated too long message...]
extHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 669194 T1960 oas.SolrTestCaseJ4.tearDown ###Ending doTestRepeater
[junit4] 2> 669215 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=664183496
[junit4] 2> 669215 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5bcd56e6
[junit4] 2> 669216 T1960 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=6,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 669216 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 669216 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 669217 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 669218 T1960 C514 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4a9f5330 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@408a6f8f),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4a9f5330 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@408a6f8f),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4a9f5330 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@408a6f8f),segFN=segments_4,generation=4}
[junit4] 2> 669218 T1960 C514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 669219 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 669219 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 669220 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776244/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776244/master/collection1/data;done=false>>]
[junit4] 2> 669220 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776244/master/collection1/data
[junit4] 2> 669220 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776244/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776244/master/collection1/data/index;done=false>>]
[junit4] 2> 669221 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776244/master/collection1/data/index
[junit4] 2> 669221 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 669293 T1960 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=371674530
[junit4] 2> 669293 T1960 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@320040cb
[junit4] 2> 669294 T1960 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> 669294 T1960 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 669294 T1960 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 669294 T1960 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 669295 T1960 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 669295 T1960 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 669295 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776364/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776364/slave/collection1/data;done=false>>]
[junit4] 2> 669296 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776364/slave/collection1/data
[junit4] 2> 669296 T1960 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776364/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776364/slave/collection1/data/index;done=false>>]
[junit4] 2> 669296 T1960 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377808776364/slave/collection1/data/index
[junit4] 2> 669297 T1960 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 669353 T1960 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=be, timezone=Pacific/Saipan
[junit4] 2> NOTE: Linux 3.2.0-52-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=55105928,total=341311488
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomMergePolicy, ConvertedLegacyTest, TestUpdate, TestMultiCoreConfBootstrap, TestStressRecovery, BasicZkTest, TestCopyFieldCollectionResource, PathHierarchyTokenizerFactoryTest, ShardSplitTest, HighlighterTest, TestIndexSearcher, AlternateDirectoryTest, TestPartialUpdateDeduplication, SuggesterTSTTest, TestDistributedGrouping, TestReloadAndDeleteDocs, PreAnalyzedFieldTest, SolrPluginUtilsTest, CustomCollectionTest, StatsComponentTest, TestSolrJ, TestLazyCores, AddBlockUpdateTest, TestSchemaResource, CoreAdminCreateDiscoverTest, SortByFunctionTest, SolrCoreCheckLockOnStartupTest, IndexBasedSpellCheckerTest, IndexSchemaRuntimeFieldTest, TestPhraseSuggestions, DistanceFunctionTest, NoCacheHeaderTest, TestCollationField, HighlighterConfigTest, TestXIncludeConfig, TestFastOutputStream, TestRecoveryHdfs, DocValuesMultiTest, TestDFRSimilarityFactory, PluginInfoTest, TestWordDelimiterFilterFactory, StressHdfsTest, MinimalSchemaTest, ChaosMonkeyNothingIsSafeTest, SpellCheckCollatorTest, TestSolrDeletionPolicy2, TestDefaultSearchFieldResource, PrimUtilsTest, RequestHandlersTest, TestSolrXmlPersistence, TestIBSimilarityFactory, LeaderElectionIntegrationTest, TestFiltering, SolrCmdDistributorTest, CurrencyFieldXmlFileTest, TestCoreDiscovery, DisMaxRequestHandlerTest, OutputWriterTest, TestBinaryResponseWriter, TestReload, SuggesterWFSTTest, SpellingQueryConverterTest, TestBinaryField, ZkSolrClientTest, BadIndexSchemaTest, TestAtomicUpdateErrorCases, TestArbitraryIndexDir, TestOmitPositions, SimplePostToolTest, JSONWriterTest, BasicDistributedZk2Test, TestAddFieldRealTimeGet, QueryResultKeyTest, CoreContainerCoreInitFailuresTest, TestDistributedSearch, HdfsDirectoryTest, DateFieldTest, MultiTermTest, ShardRoutingTest, TestReplicationHandler]
[junit4] Completed on J1 in 58.94s, 14 tests, 1 failure <<< FAILURES!
[...truncated 468 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:389: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:369: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:450: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1230: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:873: There were test failures: 324 suites, 1409 tests, 1 failure, 39 ignored (6 assumptions)
Total time: 33 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b102 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure