You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/10/06 20:55:29 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b106) - Build #
7674 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7674/
Java: 32bit/jdk1.8.0-ea-b106 -server -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sun Oct 06 21:42:42 EEST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sun Oct 06 21:42:42 EEST 2013
at __randomizedtesting.SeedInfo.seed([2F582B87B2EE790A:D82BC5DF7406D6EC]: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.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1263)
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 10002 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 112536 T350 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4] 2> 112542 T350 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 112604 T350 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44632
[junit4] 2> 112607 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 112608 T350 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 112608 T350 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master
[junit4] 2> 112608 T350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/'
[junit4] 2> 112623 T350 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/solr.xml
[junit4] 2> 112636 T350 oasc.CoreContainer.<init> New CoreContainer 1660537
[junit4] 2> 112637 T350 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/]
[junit4] 2> 112637 T350 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 112638 T350 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 112638 T350 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 112638 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 112639 T350 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 112640 T350 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 112640 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 112640 T350 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 112641 T350 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 112641 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 112643 T350 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 112643 T350 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 112645 T360 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1
[junit4] 2> 112645 T360 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/'
[junit4] 2> 112673 T360 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 112682 T360 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 112683 T360 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 112687 T360 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 112690 T360 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 112691 T360 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 112691 T360 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/
[junit4] 2> 112691 T360 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 112692 T360 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data
[junit4] 2> 112692 T360 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/index/
[junit4] 2> 112692 T360 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 112693 T360 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/index
[junit4] 2> 112694 T360 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1954428180, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 112694 T360 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_1,generation=1}
[junit4] 2> 112695 T360 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 112696 T360 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 112696 T360 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 112696 T360 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 112697 T360 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 112697 T360 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 112697 T360 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 112697 T360 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 112700 T360 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 112701 T360 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 112701 T360 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 112701 T360 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8050706126253246]
[junit4] 2> 112703 T360 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_1,generation=1}
[junit4] 2> 112703 T360 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 112703 T360 oass.SolrIndexSearcher.<init> Opening Searcher@c25690 main
[junit4] 2> 112703 T360 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 112704 T360 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 112704 T361 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c25690 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 112704 T360 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 112705 T350 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 112705 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 112706 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 112712 T350 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 112722 T350 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34725
[junit4] 2> 112722 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 112723 T350 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 112723 T350 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave
[junit4] 2> 112723 T350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/'
[junit4] 2> 112733 T350 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/solr.xml
[junit4] 2> 112742 T350 oasc.CoreContainer.<init> New CoreContainer 19530821
[junit4] 2> 112742 T350 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/]
[junit4] 2> 112743 T350 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 112743 T350 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 112743 T350 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 112744 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 112744 T350 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 112744 T350 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 112745 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 112745 T350 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 112746 T350 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 112746 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 112747 T350 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 112747 T350 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 112749 T372 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1
[junit4] 2> 112749 T372 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/'
[junit4] 2> 112765 T372 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 112771 T372 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 112772 T372 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 112775 T372 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 112777 T372 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 112777 T372 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 112778 T372 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/
[junit4] 2> 112778 T372 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 112778 T372 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data
[junit4] 2> 112779 T372 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index/
[junit4] 2> 112779 T372 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 112779 T372 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index
[junit4] 2> 112780 T372 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1954428180, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 112783 T372 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_1,generation=1}
[junit4] 2> 112783 T372 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 112784 T372 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 112784 T372 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 112784 T372 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 112785 T372 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 112785 T372 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 112785 T372 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 112785 T372 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 112786 T372 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 112787 T372 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 112787 T372 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 112788 T372 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8050706126253246]
[junit4] 2> 112788 T372 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_1,generation=1}
[junit4] 2> 112789 T372 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 112789 T372 oass.SolrIndexSearcher.<init> Opening Searcher@e748fe main
[junit4] 2> 112791 T372 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 112791 T373 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e748fe main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 112791 T372 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 112792 T350 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 112792 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 112793 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C172 name=collection1 org.apache.solr.core.SolrCore@23735d
[junit4] 2> 112802 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=4
[junit4] 2> 112806 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 112813 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C173 name=collection1 org.apache.solr.core.SolrCore@1ec2b0e
[junit4] 2> 112814 T367 C173 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 112820 T355 C172 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 112822 T356 C172 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 112825 T356 C172 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_2,generation=2}
[junit4] 2> 112825 T356 C172 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 112826 T356 C172 oass.SolrIndexSearcher.<init> Opening Searcher@1042346 main
[junit4] 2> 112827 T356 C172 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 112827 T361 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1042346 main{StandardDirectoryReader(segments_2:3:nrt _0(4.6):C1)}
[junit4] 2> 112834 T356 C172 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 2> 112838 T376 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 112838 T376 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 112841 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 112846 T376 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 112846 T376 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 112847 T376 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 112857 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=2
[junit4] 2> 112866 T376 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 112870 T376 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215814
[junit4] 2> 112870 T376 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a824b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@701d41) fullCopy=false
[junit4] 2> 112872 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112875 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112879 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112882 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112886 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112890 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112900 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112905 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112909 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112912 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 112913 T376 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 112915 T376 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 112915 T376 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 112915 T376 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 112916 T376 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 112917 T376 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 112917 T376 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_2,generation=2}
[junit4] 2> 112918 T376 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 112918 T376 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 112919 T376 oass.SolrIndexSearcher.<init> Opening Searcher@f44f9c main
[junit4] 2> 112919 T373 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f44f9c main{StandardDirectoryReader(segments_2:3:nrt _0(4.6):C1)}
[junit4] 2> 112919 T376 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215814 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215814;done=true>>]
[junit4] 2> 112919 T376 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215814
[junit4] 2> 112919 T369 C173 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:44632/solr/replication&command=fetchindex&wait=true} status=0 QTime=82
[junit4] 2> 112925 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 112927 T366 C173 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 112930 T358 C172 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 112933 T355 C172 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 112935 T355 C172 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_3,generation=3}
[junit4] 2> 112936 T355 C172 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 112937 T355 C172 oass.SolrIndexSearcher.<init> Opening Searcher@615c3 main
[junit4] 2> 112937 T355 C172 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 112937 T361 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@615c3 main{StandardDirectoryReader(segments_3:5:nrt _0(4.6):C1 _1(4.6):C1)}
[junit4] 2> 112938 T355 C172 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 2> 112948 T378 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 112949 T378 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 112951 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 112951 T378 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 112952 T378 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 112952 T378 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 112955 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1
[junit4] 2> 112956 T378 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 19
[junit4] 2> 112957 T378 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215904
[junit4] 2> 112957 T378 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@11427fe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a849ce) fullCopy=false
[junit4] 2> 112960 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112963 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112964 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 112966 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112969 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112974 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 112976 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4] 2> 112983 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 112987 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112988 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene41_0.doc because it already exists
[junit4] 2> 112992 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112996 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 112999 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4] 2> 112999 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 112999 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 112999 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene41_0.tim because it already exists
[junit4] 2> 113000 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 113000 T378 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene41_0.tip because it already exists
[junit4] 2> 113002 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 113004 T378 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 113005 T378 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 113005 T378 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 113005 T378 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 113007 T378 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 113007 T378 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 113009 T378 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_3,generation=3}
[junit4] 2> 113009 T378 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 113010 T378 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 113011 T378 oass.SolrIndexSearcher.<init> Opening Searcher@15b3f9a main
[junit4] 2> 113011 T373 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15b3f9a main{StandardDirectoryReader(segments_3:5:nrt _0(4.6):C1 _1(4.6):C1)}
[junit4] 2> 113011 T378 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215904 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215904;done=true>>]
[junit4] 2> 113011 T378 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215904
[junit4] 2> 113011 T368 C173 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:44632/solr/replication&command=fetchindex&wait=true} status=0 QTime=72
[junit4] 2> 113016 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 113017 T367 C173 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 113019 T358 C172 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 113022 T355 C172 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 113023 T355 C172 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@14cc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1225e98),segFN=segments_4,generation=4}
[junit4] 2> 113024 T355 C172 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 113024 T355 C172 oass.SolrIndexSearcher.<init> Opening Searcher@1813fe8 main
[junit4] 2> 113025 T355 C172 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 113025 T361 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1813fe8 main{StandardDirectoryReader(segments_4:7:nrt _0(4.6):C1 _1(4.6):C1 _2(4.6):C1)}
[junit4] 2> 113025 T355 C172 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 113027 T380 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 113027 T380 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 113030 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 113030 T380 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4] 2> 113031 T380 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 113031 T380 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 113033 T356 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=1
[junit4] 2> 113033 T380 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 28
[junit4] 2> 113034 T380 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215982
[junit4] 2> 113035 T380 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1de92c8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@541c0a) fullCopy=false
[junit4] 2> 113037 T358 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113038 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene41_0.doc because it already exists
[junit4] 2> 113040 T355 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113043 T357 C172 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113044 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvd because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene41_0.tim because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene41_0.tip because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvm because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 113045 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene41_0.tim because it already exists
[junit4] 2> 113046 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1.nvd because it already exists
[junit4] 2> ASYNC NEW_CORE C174 name=collection1 org.apache.solr.core.SolrCore@23735d
[junit4] 2> 113053 T356 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113054 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene41_0.tip because it already exists
[junit4] 2> 113056 T358 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113057 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0.nvm because it already exists
[junit4] 2> 113059 T355 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113062 T357 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113063 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4] 2> 113065 T356 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113066 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4] 2> 113066 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene41_0.doc because it already exists
[junit4] 2> 113066 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4] 2> 113068 T358 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113069 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 113070 T380 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 113071 T355 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 113072 T380 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 113073 T380 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 113073 T380 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 113073 T380 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 113074 T380 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 113074 T380 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 113076 T380 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@10d80a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c03e5),segFN=segments_4,generation=4}
[junit4] 2> 113076 T380 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 113076 T380 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 113077 T380 oass.SolrIndexSearcher.<init> Opening Searcher@eb7955 main
[junit4] 2> 113078 T373 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@eb7955 main{StandardDirectoryReader(segments_4:7:nrt _0(4.6):C1 _1(4.6):C1 _2(4.6):C1)}
[junit4] 2> 113078 T380 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215982 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215982;done=true>>]
[junit4] 2> 113078 T380 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index.20131006214215982
[junit4] 2> ASYNC NEW_CORE C175 name=collection1 org.apache.solr.core.SolrCore@1ec2b0e
[junit4] 2> 113078 T369 C175 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:44632/solr/replication&command=fetchindex&wait=true} status=0 QTime=52
[junit4] 2> 113082 T350 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 113088 T350 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34692
[junit4] 2> 113089 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 113089 T350 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 113090 T350 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater
[junit4] 2> 113090 T350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/'
[junit4] 2> 113098 T350 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/solr.xml
[junit4] 2> 113104 T350 oasc.CoreContainer.<init> New CoreContainer 14624663
[junit4] 2> 113105 T350 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/]
[junit4] 2> 113105 T350 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 113105 T350 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 113106 T350 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 113106 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 113106 T350 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 113106 T350 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 113107 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 113107 T350 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 113107 T350 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 113107 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 113108 T350 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 113109 T350 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 113110 T391 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1
[junit4] 2> 113110 T391 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/'
[junit4] 2> 113125 T391 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 113131 T391 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 113131 T391 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 113133 T391 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 113136 T391 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 113136 T391 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 113136 T391 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/
[junit4] 2> 113136 T391 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 113137 T391 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data
[junit4] 2> 113137 T391 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/index/
[junit4] 2> 113137 T391 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 113138 T391 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/index
[junit4] 2> 113138 T391 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1954428180, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 113139 T391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4057b8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ff31c8),segFN=segments_1,generation=1}
[junit4] 2> 113139 T391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 113140 T391 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 113140 T391 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 113141 T391 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 113141 T391 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 113141 T391 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 113141 T391 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 113141 T391 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 113142 T391 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 113143 T391 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 113143 T391 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 113143 T391 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8050706126253246]
[junit4] 2> 113144 T391 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4057b8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ff31c8),segFN=segments_1,generation=1}
[junit4] 2> 113144 T391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 113145 T391 oass.SolrIndexSearcher.<init> Opening Searcher@1e554ba main
[junit4] 2> 113145 T391 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 113145 T391 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 113145 T391 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 113145 T391 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 113145 T392 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e554ba main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 113145 T391 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 113146 T350 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 113147 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 113147 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 113153 T357 C174 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C176 name=collection1 org.apache.solr.core.SolrCore@2e353d
[junit4] 2> 113157 T387 C176 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=7
[junit4] 2> 113179 T350 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=14624663
[junit4] 2> 113180 T350 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2e353d
[junit4] 2> 113180 T350 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> 113181 T350 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 113181 T350 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 113181 T350 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 113182 T350 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 113182 T350 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 113183 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/index;done=false>>]
[junit4] 2> 113183 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data/index
[junit4] 2> 113183 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data;done=false>>]
[junit4] 2> 113184 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936027/repeater/collection1/data
[junit4] 2> 113184 T350 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 113238 T350 oas.SolrTestCaseJ4.tearDown ###Ending doTestDetails
[junit4] 2> 113261 T350 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1660537
[junit4] 2> 113261 T350 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@23735d
[junit4] 2> 113262 T350 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 113262 T350 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 113262 T350 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 113263 T350 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 113264 T350 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 113264 T350 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 113265 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data;done=false>>]
[junit4] 2> 113265 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data
[junit4] 2> 113265 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/index;done=false>>]
[junit4] 2> 113266 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935485/master/collection1/data/index
[junit4] 2> 113267 T350 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 113339 T350 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=19530821
[junit4] 2> 113341 T350 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ec2b0e
[junit4] 2> 113342 T350 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> 113342 T350 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 113342 T350 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 113343 T350 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 113343 T350 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 113344 T350 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 113344 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index;done=false>>]
[junit4] 2> 113344 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data/index
[junit4] 2> 113345 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data;done=false>>]
[junit4] 2> 113345 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084935655/slave/collection1/data
[junit4] 2> 113346 T350 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 113405 T350 oas.SolrTestCaseJ4.setUp ###Starting doTestIndexAndConfigReplication
[junit4] 2> 113409 T350 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 113413 T350 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49034
[junit4] 2> 113414 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 113415 T350 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 113415 T350 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master
[junit4] 2> 113415 T350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/'
[junit4] 2> 113431 T350 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/solr.xml
[junit4] 2> 113441 T350 oasc.CoreContainer.<init> New CoreContainer 22585257
[junit4] 2> 113441 T350 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/]
[junit4] 2> 113442 T350 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 113442 T350 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 113443 T350 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 113443 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 113443 T350 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 113444 T350 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 113444 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 113444 T350 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 113445 T350 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 113445 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 113447 T350 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 113448 T350 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 113450 T403 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1
[junit4] 2> 113450 T403 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/'
[junit4] 2> 113467 T403 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 113473 T403 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 113473 T403 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 113475 T403 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 113478 T403 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 113478 T403 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 113479 T403 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/data/
[junit4] 2> 113479 T403 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 113480 T403 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/data
[junit4] 2> 113480 T403 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/data/index/
[junit4] 2> 113480 T403 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 113481 T403 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936354/master/collection1/data/index
[junit4] 2> 113481 T403 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1954428180, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 113482 T403 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17125da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cb755d),segFN=segments_1,generation=1}
[junit4] 2> 113482 T403 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 113483 T403 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 113483 T403 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 113483 T403 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 113484 T403 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 113484 T403 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 113484 T403 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 113484 T403 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 113486 T403 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 113487 T403 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 113487 T403 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 113488 T403 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8050706126253246]
[junit4] 2> 113488 T403 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17125da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cb755d),segFN=segments_1,generation=1}
[junit4] 2> 113488 T403 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 113488 T403 oass.SolrIndexSearcher.<init> Opening Searcher@1c0b5cb main
[junit4] 2> 113489 T403 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 113489 T403 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 113489 T404 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c0b5cb main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 113489 T403 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 113490 T350 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 113490 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 113491 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 113494 T350 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 113496 T350 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46904
[junit4] 2> 113497 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 113497 T350 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 113498 T350 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave
[junit4] 2> 113498 T350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/'
[junit4] 2> 113508 T350 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/solr.xml
[junit4] 2> 113514 T350 oasc.CoreContainer.<init> New CoreContainer 30772379
[junit4] 2> 113515 T350 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/]
[junit4] 2> 113516 T350 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 113517 T350 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 113517 T350 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 113518 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 113518 T350 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 113518 T350 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 113518 T350 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 113519 T350 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 113519 T350 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 113519 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 113520 T350 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 113520 T350 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 113522 T415 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1
[junit4] 2> 113522 T415 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/'
[junit4] 2> 113543 T415 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 113554 T415 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 113555 T415 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 113557 T415 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 113559 T415 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 113559 T415 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 113560 T415 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/data/
[junit4] 2> 113560 T415 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 113560 T415 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/data
[junit4] 2> 113560 T415 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/data/index/
[junit4] 2> 113560 T415 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 113561 T415 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084936439/slave/collection1/data/index
[junit4] 2> 113561 T415 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1954428180, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 113562 T415 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@e98349 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@151a1f6),segFN=segments_1,generation=1}
[junit4] 2> 113562 T415 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 113563 T415 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 113563 T415 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 113563 T415 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 113563 T415 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 113563 T415 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 113563 T415 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 113564 T415 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 113565 T415 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 113565 T415 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 113565 T415 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 113566 T415 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8050706126253246]
[junit4] 2> 113566 T415 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@e98349 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@151a1f6),segFN=segments_1,generation=1}
[junit4] 2> 113566 T415 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 113567 T415 oass.SolrIndexSearcher.<init> Opening Searcher@142b826 main
[junit4] 2> 113567 T415 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 113567 T416 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@142b826 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 113567 T415 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 113568 T350 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 113568 T350 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 113569 T350 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C177 name=collection1 org.apache.solr.core.SolrCore@7e19ed
[junit4] 2> 113572 T402 C177 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> 113575 T400 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 113577 T399 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 113579 T401 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 113581 T402 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 113584 T400 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4] 2> 113586 T399 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 113588 T401 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 113590 T402 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 113592 T400 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 113594 T399 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 113597 T401 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 113600 T402 C177 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 1
[junit4] 2> 113602 T400 C177 oasup.LogUpdateProcessor.finish [collection1]
[...truncated too long message...]
ectory@13bb03a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c1fbc),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@13bb03a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c1fbc),segFN=segments_2,generation=2}
[junit4] 2> 177326 T1027 C231 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 177335 T1027 C231 oass.SolrIndexSearcher.<init> Opening Searcher@1427f76 main
[junit4] 2> 177335 T1027 C231 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 177335 T1029 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1427f76 main{StandardDirectoryReader(segments_2:4:nrt _0(4.6):C492)}
[junit4] 2> 177336 T1027 C231 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 22
[junit4] 2> 177353 T1020 C231 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1
[junit4] 2> 177370 T1025 C231 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=backup} status=0 QTime=17
[junit4] 2> 177374 T1033 oash.SnapShooter.createSnapshot Creating backup snapshot...
[junit4] 2> 177558 T1026 C231 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=3
[junit4] 2> 178053 T1017 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:36265/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:36265/solr
[junit4] 2> 178566 T1026 C231 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=backup} status=0 QTime=0
[junit4] 2> 178566 T1027 C231 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0
[junit4] 2> 178567 T1036 oash.SnapShooter.createSnapshot Creating backup snapshot...
[junit4] 2> 178768 T1020 C231 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0
[junit4] 2> 178772 T350 oas.SolrTestCaseJ4.tearDown ###Ending doTestBackup
[junit4] 2> 178793 T350 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7789592
[junit4] 2> 178794 T350 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ef4ec7
[junit4] 2> 178795 T350 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=492,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 178795 T350 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 178796 T350 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 178796 T350 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 178797 T350 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 178797 T350 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 178798 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998455/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998455/master/collection1/data;done=false>>]
[junit4] 2> 178798 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998455/master/collection1/data
[junit4] 2> 178798 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998455/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998455/master/collection1/data/index;done=false>>]
[junit4] 2> 178799 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998455/master/collection1/data/index
[junit4] 2> 178799 T350 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 178874 T350 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7988667
[junit4] 2> 178875 T350 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1e5bba8
[junit4] 2> 178876 T350 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> 178876 T350 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 178877 T350 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 178877 T350 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 178878 T350 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 178878 T350 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 178879 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998680/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998680/slave/collection1/data;done=false>>]
[junit4] 2> 178879 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998680/slave/collection1/data
[junit4] 2> 178880 T350 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998680/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998680/slave/collection1/data/index;done=false>>]
[junit4] 2> 178880 T350 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381084998680/slave/collection1/data/index
[junit4] 2> 178881 T350 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 178938 T350 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=DefaultSimilarity, locale=de_AT, timezone=Europe/Vilnius
[junit4] 2> NOTE: Linux 3.8.0-31-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=138557080,total=245366784
[junit4] 2> NOTE: All tests run in this JVM: [ReturnFieldsTest, LegacyHTMLStripCharFilterTest, TestNRTOpen, LoggingHandlerTest, InfoHandlerTest, BJQParserTest, TestDocumentBuilder, AddSchemaFieldsUpdateProcessorFactoryTest, TestRealTimeGet, DocValuesMissingTest, TestLFUCache, TestFieldResource, TestDocSet, SyncSliceTest, RegexBoostProcessorTest, SchemaVersionSpecificBehaviorTest, AutoCommitTest, PrimitiveFieldTypeTest, TestSolrQueryParserDefaultOperatorResource, TestSolrIndexConfig, TestReplicationHandler]
[junit4] Completed on J0 in 66.47s, 14 tests, 1 failure <<< FAILURES!
[...truncated 876 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:408: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:451: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 327 suites, 1453 tests, 1 failure, 38 ignored (6 assumptions)
Total time: 37 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b106 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b106) - Build #
7675 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7675/
Java: 32bit/jdk1.8.0-ea-b106 -server -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sun Oct 06 23:14:13 AST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sun Oct 06 23:14:13 AST 2013
at __randomizedtesting.SeedInfo.seed([652B8ABD5D9CA454:BE808A7B58B4CDE7]: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 10313 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 535457 T1760 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 535461 T1760 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 535473 T1760 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44662
[junit4] 2> 535477 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 535477 T1760 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 535478 T1760 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master
[junit4] 2> 535478 T1760 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/'
[junit4] 2> 535487 T1760 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/solr.xml
[junit4] 2> 535499 T1760 oasc.CoreContainer.<init> New CoreContainer 20384507
[junit4] 2> 535500 T1760 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/]
[junit4] 2> 535500 T1760 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 535500 T1760 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 535501 T1760 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 535501 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 535501 T1760 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 535502 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 535502 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 535502 T1760 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 535502 T1760 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 535503 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 535504 T1760 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 535504 T1760 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 535507 T1770 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1
[junit4] 2> 535507 T1770 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/'
[junit4] 2> 535519 T1770 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 535524 T1770 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 535524 T1770 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 535526 T1770 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 535528 T1770 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 535529 T1770 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 535529 T1770 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/
[junit4] 2> 535529 T1770 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 535530 T1770 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data
[junit4] 2> 535530 T1770 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index/
[junit4] 2> 535530 T1770 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 535530 T1770 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index
[junit4] 2> 535531 T1770 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 535531 T1770 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@13a9f5d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@93a5b3),segFN=segments_1,generation=1}
[junit4] 2> 535531 T1770 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535532 T1770 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 535532 T1770 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 535532 T1770 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 535532 T1770 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 535532 T1770 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 535533 T1770 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 535533 T1770 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 535534 T1770 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 535535 T1770 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 535535 T1770 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 535535 T1770 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=16.998046875, floorSegmentMB=1.953125, forceMergeDeletesPctAllowed=16.596201529404308, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5872886184200483
[junit4] 2> 535536 T1770 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@13a9f5d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@93a5b3),segFN=segments_1,generation=1}
[junit4] 2> 535536 T1770 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535536 T1770 oass.SolrIndexSearcher.<init> Opening Searcher@1be935 main
[junit4] 2> 535536 T1770 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 535536 T1770 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 535537 T1770 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 535537 T1771 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1be935 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 535537 T1760 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 535538 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 535538 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 535542 T1760 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 535543 T1760 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56612
[junit4] 2> 535544 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 535544 T1760 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 535544 T1760 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave
[junit4] 2> 535545 T1760 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/'
[junit4] 2> 535552 T1760 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/solr.xml
[junit4] 2> 535558 T1760 oasc.CoreContainer.<init> New CoreContainer 29583043
[junit4] 2> 535558 T1760 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/]
[junit4] 2> 535559 T1760 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 535559 T1760 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 535559 T1760 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 535560 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 535560 T1760 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 535560 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 535560 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 535561 T1760 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 535561 T1760 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 535562 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 535562 T1760 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 535563 T1760 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 535564 T1782 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1
[junit4] 2> 535564 T1782 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/'
[junit4] 2> 535576 T1782 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 535582 T1782 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 535582 T1782 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 535584 T1782 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 535586 T1782 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 535586 T1782 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 535586 T1782 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/
[junit4] 2> 535586 T1782 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 535587 T1782 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data
[junit4] 2> 535587 T1782 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index/
[junit4] 2> 535587 T1782 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 535588 T1782 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index
[junit4] 2> 535588 T1782 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 535589 T1782 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dd420e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a7830),segFN=segments_1,generation=1}
[junit4] 2> 535589 T1782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535589 T1782 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 535590 T1782 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 535590 T1782 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 535590 T1782 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 535590 T1782 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 535590 T1782 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 535590 T1782 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 535591 T1782 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 535592 T1782 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 535592 T1782 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 535592 T1782 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=16.998046875, floorSegmentMB=1.953125, forceMergeDeletesPctAllowed=16.596201529404308, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5872886184200483
[junit4] 2> 535592 T1782 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dd420e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a7830),segFN=segments_1,generation=1}
[junit4] 2> 535593 T1782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535593 T1782 oass.SolrIndexSearcher.<init> Opening Searcher@15c9de4 main
[junit4] 2> 535594 T1782 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 535594 T1783 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15c9de4 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 535594 T1782 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 535595 T1760 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 535595 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 535596 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 535618 T1760 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=29583043
[junit4] 2> 535619 T1760 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@98897d
[junit4] 2> 535619 T1760 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> 535620 T1760 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 535620 T1760 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 535620 T1760 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 535621 T1760 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 535621 T1760 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 535621 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index;done=false>>]
[junit4] 2> 535622 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index
[junit4] 2> 535622 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data;done=false>>]
[junit4] 2> 535622 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data
[junit4] 2> 535623 T1760 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 535675 T1760 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 535677 T1760 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40306
[junit4] 2> 535677 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 535677 T1760 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 535678 T1760 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave
[junit4] 2> 535678 T1760 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/'
[junit4] 2> 535685 T1760 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/solr.xml
[junit4] 2> 535690 T1760 oasc.CoreContainer.<init> New CoreContainer 20628308
[junit4] 2> 535691 T1760 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/]
[junit4] 2> 535691 T1760 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 535692 T1760 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 535692 T1760 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 535692 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 535692 T1760 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 535693 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 535693 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 535693 T1760 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 535693 T1760 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 535694 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 535694 T1760 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 535695 T1760 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 535696 T1795 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1
[junit4] 2> 535696 T1795 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/'
[junit4] 2> 535708 T1795 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 535712 T1795 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 535713 T1795 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 535714 T1795 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 535716 T1795 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 535716 T1795 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 535717 T1795 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/
[junit4] 2> 535717 T1795 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 535717 T1795 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data
[junit4] 2> 535717 T1795 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index/
[junit4] 2> 535717 T1795 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 535718 T1795 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index
[junit4] 2> 535718 T1795 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 535719 T1795 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17960ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14dd468),segFN=segments_1,generation=1}
[junit4] 2> 535719 T1795 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535719 T1795 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 535719 T1795 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 535720 T1795 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 535720 T1795 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 535720 T1795 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 535720 T1795 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 535720 T1795 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 535721 T1795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 535722 T1795 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 535722 T1795 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 535722 T1795 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=16.998046875, floorSegmentMB=1.953125, forceMergeDeletesPctAllowed=16.596201529404308, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5872886184200483
[junit4] 2> 535722 T1795 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17960ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14dd468),segFN=segments_1,generation=1}
[junit4] 2> 535723 T1795 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535723 T1795 oass.SolrIndexSearcher.<init> Opening Searcher@14502d7 main
[junit4] 2> 535723 T1795 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 535723 T1796 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14502d7 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 535723 T1795 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 535724 T1760 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 535724 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 535725 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 535746 T1760 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20384507
[junit4] 2> 535747 T1760 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d4a63d
[junit4] 2> 535747 T1760 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> 535748 T1760 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 535748 T1760 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 535748 T1760 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 535749 T1760 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 535749 T1760 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 535749 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data;done=false>>]
[junit4] 2> 535750 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data
[junit4] 2> 535750 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index;done=false>>]
[junit4] 2> 535750 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index
[junit4] 2> 535751 T1760 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 535803 T1760 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 535805 T1760 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58648
[junit4] 2> 535805 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 535806 T1760 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 535806 T1760 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master
[junit4] 2> 535806 T1760 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/'
[junit4] 2> 535813 T1760 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/solr.xml
[junit4] 2> 535818 T1760 oasc.CoreContainer.<init> New CoreContainer 3039818
[junit4] 2> 535819 T1760 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/]
[junit4] 2> 535819 T1760 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 535819 T1760 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 535820 T1760 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 535820 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 535820 T1760 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 535820 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 535821 T1760 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 535821 T1760 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 535821 T1760 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 535822 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 535822 T1760 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 535823 T1760 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 535824 T1807 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1
[junit4] 2> 535824 T1807 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/'
[junit4] 2> 535835 T1807 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 535845 T1807 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 535846 T1807 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 535847 T1807 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 535849 T1807 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 535850 T1807 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 535850 T1807 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/
[junit4] 2> 535850 T1807 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 535850 T1807 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data
[junit4] 2> 535850 T1807 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index/
[junit4] 2> 535851 T1807 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 535851 T1807 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452819/master/collection1/data/index
[junit4] 2> 535851 T1807 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 535852 T1807 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@fafdf3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12d083f),segFN=segments_1,generation=1}
[junit4] 2> 535852 T1807 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535852 T1807 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 535853 T1807 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 535853 T1807 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 535853 T1807 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 535853 T1807 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 535853 T1807 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 535853 T1807 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 535854 T1807 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 535855 T1807 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 535855 T1807 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 535855 T1807 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=16.998046875, floorSegmentMB=1.953125, forceMergeDeletesPctAllowed=16.596201529404308, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5872886184200483
[junit4] 2> 535855 T1807 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@fafdf3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12d083f),segFN=segments_1,generation=1}
[junit4] 2> 535856 T1807 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 535856 T1807 oass.SolrIndexSearcher.<init> Opening Searcher@108c61f main
[junit4] 2> 535856 T1807 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 535856 T1807 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 535856 T1807 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 535856 T1808 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@108c61f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 535857 T1760 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 535857 T1760 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 535858 T1760 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C327 name=collection1 org.apache.solr.core.SolrCore@14a44f6
[junit4] 2> 535862 T1803 C327 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 535862 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C328 name=collection1 org.apache.solr.core.SolrCore@1a71b4a
[junit4] 2> 535865 T1791 C328 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 535865 T1791 C328 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 0
[junit4] 2> 535868 T1792 C328 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 535869 T1792 C328 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17960ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14dd468),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17960ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14dd468),segFN=segments_2,generation=2}
[junit4] 2> 535870 T1792 C328 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 535871 T1792 C328 oass.SolrIndexSearcher.<init> Opening Searcher@15c4923 main
[junit4] 2> 535871 T1792 C328 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 535871 T1796 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15c4923 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 535871 T1792 C328 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4] 2> 535875 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 535876 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 535878 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 535880 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 535882 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 535883 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 535885 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 535886 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 535888 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 535890 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 535892 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 535893 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 535895 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 535897 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 535899 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 535900 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 535902 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 535904 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 535906 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 535908 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 535909 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 535911 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 535913 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 535915 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 535917 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 535919 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 535921 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 1
[junit4] 2> 535922 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 535924 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 535926 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 535928 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 535930 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4] 2> 535931 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 535933 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 535935 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 535937 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 535938 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 535940 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 535942 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 535943 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 535945 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 535947 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 535949 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 535950 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 535952 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4] 2> 535954 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 0
[junit4] 2> 535955 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 535957 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 535959 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 535960 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 0
[junit4] 2> 535962 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4] 2> 535964 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 535966 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 1
[junit4] 2> 535967 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 535969 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 0
[junit4] 2> 535971 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4] 2> 535972 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4] 2> 535974 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4] 2> 535976 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4] 2> 535978 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 1
[junit4] 2> 535979 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 0
[junit4] 2> 535981 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4] 2> 535983 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4] 2> 535984 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4] 2> 535986 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 0
[junit4] 2> 535989 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 0
[junit4] 2> 535991 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4] 2> 535993 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 1
[junit4] 2> 535994 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 535996 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 535998 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4] 2> 535999 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 0
[junit4] 2> 536001 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 0
[junit4] 2> 536003 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4] 2> 536005 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 1
[junit4] 2> 536006 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4] 2> 536008 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 0
[junit4] 2> 536009 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4] 2> 536012 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4] 2> 536014 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 0
[junit4] 2> 536015 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4] 2> 536017 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4] 2> 536019 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4] 2> 536021 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 0
[junit4] 2> 536022 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 536024 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4] 2> 536026 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 1
[junit4] 2> 536027 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4] 2> 536029 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4] 2> 536031 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4] 2> 536032 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 0
[junit4] 2> 536034 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4] 2> 536036 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 536038 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4] 2> 536039 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4] 2> 536041 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4] 2> 536043 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 536044 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4] 2> 536046 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 0
[junit4] 2> 536048 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4] 2> 536050 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 1
[junit4] 2> 536051 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4] 2> 536053 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4] 2> 536055 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 536057 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 536059 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4] 2> 536061 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4] 2> 536063 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4] 2> 536065 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 1
[junit4] 2> 536066 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4] 2> 536068 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4] 2> 536070 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4] 2> 536072 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4] 2> 536074 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4] 2> 536076 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 536078 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 0
[junit4] 2> 536079 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4] 2> 536081 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4] 2> 536083 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 0
[junit4] 2> 536085 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 536086 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4] 2> 536088 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4] 2> 536089 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4] 2> 536091 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 536093 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 536094 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 0
[junit4] 2> 536096 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4] 2> 536098 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4] 2> 536100 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4] 2> 536102 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4] 2> 536103 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 536105 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4] 2> 536107 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4] 2> 536108 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 0
[junit4] 2> 536110 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4] 2> 536112 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4] 2> 536113 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 0
[junit4] 2> 536115 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4] 2> 536117 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 0
[junit4] 2> 536119 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 1
[junit4] 2> 536121 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 0
[junit4] 2> 536123 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 0
[junit4] 2> 536124 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4] 2> 536126 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4] 2> 536127 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 536129 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 0
[junit4] 2> 536131 T1806 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4] 2> 536132 T1803 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4] 2> 536134 T1804 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 0
[junit4] 2> 536136 T1805 C327 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 536138 T1805 C327 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@fafdf3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12d083f),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@fafdf3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12d083f),segFN=segments_2,generation=2}
[junit4] 2> 536138 T1805 C327 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 536139 T1805 C327 oass.SolrIndexSearcher.<init> Opening Searcher@8c8872 main
[junit4] 2> 536140 T1805 C327 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 536140 T1808 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8c8872 main{StandardDirectoryReader(segments_2:4:nrt _0(4.6):C149)}
[junit4] 2> 536140 T1805 C327 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 536148 T1806 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=149 status=0 QTime=6
[junit4] 2> 536150 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 536153 T1810 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 536153 T1810 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 536154 T1803 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 536155 T1810 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 536155 T1810 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 536155 T1810 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 536158 T1804 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=2
[junit4] 2> 536158 T1810 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 536159 T1810 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index.20131006231413520
[junit4] 2> 536159 T1810 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@140a425 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e0148) fullCopy=true
[junit4] 2> 536161 T1805 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536164 T1806 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536167 T1803 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536169 T1804 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536172 T1805 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536174 T1806 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536177 T1803 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536179 T1804 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536182 T1805 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536184 T1806 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 536185 T1810 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 536185 T1810 oash.SnapPuller.downloadConfFiles Starting download of configuration files from master: [{name=schema.xml, checksum=1505963717, size=1634}]
[junit4] 2> 536190 T1803 C327 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&wt=filestream&cf=schema.xml&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> 536194 T1810 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20131006231413520
[junit4] 2> 536195 T1810 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17960ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14dd468)
[junit4] 2> 536195 T1810 oash.SnapPuller.fetchLatestIndex Configuration files are modified, core will be reloaded
[junit4] 2> 536196 T1810 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17960ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14dd468)
[junit4] 2> 536196 T1810 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 536196 T1810 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 536196 T1810 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 536197 T1812 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1
[junit4] 2> 536197 T1812 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/'
[junit4] 2> 536197 T1810 oasc.SolrCore.getNewIndexDir New index directory detected: old=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index/ new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index.20131006231413520
[junit4] 2> 536197 T1810 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=49.189453125, floorSegmentMB=0.8212890625, forceMergeDeletesPctAllowed=4.451980742845471, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32054328459459447
[junit4] 2> 536198 T1810 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@140a425 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e0148),segFN=segments_2,generation=2}
[junit4] 2> 536198 T1810 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 536199 T1810 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 536210 T1812 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 536215 T1812 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 536215 T1812 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 536217 T1812 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 536219 T1812 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 536249 T1812 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/
[junit4] 2> 536250 T1812 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 536250 T1810 oass.SolrIndexSearcher.<init> Opening Searcher@aa30f3 main
[junit4] 2> 536250 T1812 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index.20131006231413520
[junit4] 2> 536250 T1796 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@aa30f3 main{StandardDirectoryReader(segments_2:4:nrt _0(4.6):C149)}
[junit4] 2> 536250 T1812 oasc.SolrCore.initIndex ERROR [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index.20131006231413520' is locked. Throwing exception
[junit4] 2> 536250 T1792 C328 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:58648/solr/replication&command=fetchindex&wait=true} status=0 QTime=98
[junit4] 2> 536250 T1812 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ac8043
[junit4] 2> 536251 T1812 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 536251 T1812 oasc.CoreContainer.recordAndThrow ERROR Unable to reload core: collection1 org.apache.solr.common.SolrException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:835)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:414)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:681)
[junit4] 2> at org.apache.solr.handler.SnapPuller$2.run(SnapPuller.java:688)
[junit4] 2> Caused by: org.apache.lucene.store.LockObtainFailedException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:491)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:755)
[junit4] 2> ... 3 more
[junit4] 2>
[junit4] 2> 536251 T1812 oash.SnapPuller$2.run ERROR Could not reload core org.apache.solr.common.SolrException: Unable to reload core: collection1
[junit4] 2> at org.apache.solr.core.CoreContainer.recordAndThrow(CoreContainer.java:936)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:691)
[junit4] 2> at org.apache.solr.handler.SnapPuller$2.run(SnapPuller.java:688)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:835)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:414)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:681)
[junit4] 2> ... 1 more
[junit4] 2> Caused by: org.apache.lucene.store.LockObtainFailedException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:491)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:755)
[junit4] 2> ... 3 more
[junit4] 2>
[junit4] 2> 536252 T1792 C328 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index;done=true>>]
[junit4] 2> 536252 T1792 C328 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090452900/slave/collection1/data/index
[junit4] 2> 536255 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 536460 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 536663 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 536867 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 537071 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 537275 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 537480 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 537684 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 537888 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 538093 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 538298 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 538503 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 538707 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 538912 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 539116 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 539321 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 539526 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 539732 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 539938 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 540143 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 540349 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 540554 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 540759 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 540964 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 541169 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 541374 T1792 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 541580 T1791 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 541785 T1794 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 541990 T1793 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admi
[...truncated too long message...]
.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data/index.20131006231513005 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data/index.20131006231513005;done=true>>]
[junit4] 2> 595676 T2428 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data/index.20131006231513005
[junit4] 2> 595772 T2424 C380 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> 595773 T1760 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 595793 T1760 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1986857
[junit4] 2> 595794 T1760 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1bcd904
[junit4] 2> 595794 T1760 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> 595795 T1760 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 595795 T1760 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 595795 T1760 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 595796 T1760 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 595796 T1760 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 595797 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512334/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512334/master/collection1/data/index;done=false>>]
[junit4] 2> 595797 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512334/master/collection1/data/index
[junit4] 2> 595797 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512334/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512334/master/collection1/data;done=false>>]
[junit4] 2> 595798 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512334/master/collection1/data
[junit4] 2> 595798 T1760 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 595870 T1760 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15734586
[junit4] 2> 595873 T1760 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c1fd50
[junit4] 2> 595873 T1760 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> 595874 T1760 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 595874 T1760 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 595874 T1760 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 595875 T1760 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 595875 T1760 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 595876 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data/index;done=false>>]
[junit4] 2> 595876 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data/index
[junit4] 2> 595877 T1760 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data;done=false>>]
[junit4] 2> 595877 T1760 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1381090512394/slave/collection1/data
[junit4] 2> 595878 T1760 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 595934 T1760 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=zh_CN, timezone=Asia/Bahrain
[junit4] 2> NOTE: Linux 3.8.0-31-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=52285392,total=353107968
[junit4] 2> NOTE: All tests run in this JVM: [SimplePostToolTest, DirectSolrConnectionTest, SpellingQueryConverterTest, TestStressLucene, TestSerializedLuceneMatchVersion, TestFieldCollectionResource, DirectSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, TestZkChroot, IndexSchemaRuntimeFieldTest, TestQuerySenderNoQuery, PathHierarchyTokenizerFactoryTest, OutputWriterTest, TestBinaryResponseWriter, XmlUpdateRequestHandlerTest, SortByFunctionTest, TestSchemaVersionResource, ChaosMonkeyNothingIsSafeTest, NumericFieldsTest, TestSolrDeletionPolicy2, AliasIntegrationTest, TestBinaryField, AssignTest, BasicDistributedZk2Test, SpellCheckComponentTest, SuggesterWFSTTest, DisMaxRequestHandlerTest, CoreContainerCoreInitFailuresTest, TestSolrJ, TestCoreDiscovery, TestUpdate, TestIndexSearcher, CollectionsAPIDistributedZkTest, TestInfoStreamLogging, SignatureUpdateProcessorFactoryTest, TestNumberUtils, TestCollationField, PreAnalyzedUpdateProcessorTest, DocValuesMultiTest, PluginInfoTest, TestFastWriter, SolrPluginUtilsTest, XsltUpdateRequestHandlerTest, TestFoldingMultitermQuery, LeaderElectionIntegrationTest, TestAtomicUpdateErrorCases, TestDFRSimilarityFactory, SolrRequestParserTest, DebugComponentTest, RequiredFieldsTest, DistanceFunctionTest, TestLazyCores, IndexBasedSpellCheckerTest, TestFuzzyAnalyzedSuggestions, ChaosMonkeySafeLeaderTest, HardAutoCommitTest, TestDistributedGrouping, BlockDirectoryTest, CurrencyFieldXmlFileTest, NotRequiredUniqueKeyTest, TestIndexingPerformance, PreAnalyzedFieldTest, TestWordDelimiterFilterFactory, TestRangeQuery, ConvertedLegacyTest, BasicZkTest, HighlighterTest, TestClassNameShortening, ZkSolrClientTest, BadCopyFieldTest, TestCSVLoader, ShardRoutingCustomTest, TestSweetSpotSimilarityFactory, TestPhraseSuggestions, FileUtilsTest, MinimalSchemaTest, TestCollationKeyRangeQueries, CurrencyFieldOpenExchangeTest, TestStressReorder, TestReplicationHandler]
[junit4] Completed on J1 in 60.49s, 14 tests, 1 failure <<< FAILURES!
[...truncated 483 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:408: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:451: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 327 suites, 1453 tests, 1 failure, 38 ignored (6 assumptions)
Total time: 36 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b106 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure