You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/31 20:52:45 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b102) - Build #
7167 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7167/
Java: 32bit/jdk1.8.0-ea-b102 -server -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sat Aug 31 19:41:26 BST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sat Aug 31 19:41:26 BST 2013
at __randomizedtesting.SeedInfo.seed([FC170185238B0EEF:27BC014326A3675C]: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 9960 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 349881 T1507 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 349888 T1507 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 349918 T1507 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60886
[junit4] 2> 349918 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 349919 T1507 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 349919 T1507 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master
[junit4] 2> 349920 T1507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/'
[junit4] 2> 349939 T1507 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-1377974455022/master/solr.xml
[junit4] 2> 349966 T1507 oasc.CoreContainer.<init> New CoreContainer 10874868
[junit4] 2> 349966 T1507 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/]
[junit4] 2> 349973 T1507 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 349974 T1507 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 349974 T1507 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 349975 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 349975 T1507 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 349975 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 349976 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 349976 T1507 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 349977 T1507 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 349977 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 349986 T1507 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 349987 T1507 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 350005 T1518 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1
[junit4] 2> 350005 T1518 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/'
[junit4] 2> 350037 T1518 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 350065 T1518 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 350065 T1518 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 350068 T1518 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 350071 T1518 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 350072 T1518 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 350072 T1518 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/data/
[junit4] 2> 350073 T1518 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 350085 T1518 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-1377974455022/master/collection1/data
[junit4] 2> 350085 T1518 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-1377974455022/master/collection1/data/index/
[junit4] 2> 350085 T1518 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-1377974455022/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 350086 T1518 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-1377974455022/master/collection1/data/index
[junit4] 2> 350086 T1518 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542494337, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 350088 T1518 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9d4b50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e58ce7),segFN=segments_1,generation=1}
[junit4] 2> 350088 T1518 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350089 T1518 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 350089 T1518 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 350089 T1518 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 350089 T1518 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 350090 T1518 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 350090 T1518 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 350090 T1518 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 350092 T1518 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 350093 T1518 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 350094 T1518 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 350105 T1518 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=7.0966796875, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=26.7651242959393, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2544053572133088
[junit4] 2> 350106 T1518 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9d4b50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e58ce7),segFN=segments_1,generation=1}
[junit4] 2> 350106 T1518 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350106 T1518 oass.SolrIndexSearcher.<init> Opening Searcher@196d0e2 main
[junit4] 2> 350107 T1518 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 350107 T1518 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 350107 T1518 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 350107 T1519 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@196d0e2 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 350108 T1507 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 350109 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 350109 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 350117 T1507 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 350126 T1507 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46147
[junit4] 2> 350127 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 350127 T1507 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 350127 T1507 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave
[junit4] 2> 350127 T1507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/'
[junit4] 2> 350156 T1507 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-1377974455252/slave/solr.xml
[junit4] 2> 350163 T1507 oasc.CoreContainer.<init> New CoreContainer 33513053
[junit4] 2> 350164 T1507 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/]
[junit4] 2> 350165 T1507 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 350165 T1507 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 350166 T1507 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 350166 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 350166 T1507 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 350167 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 350167 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 350167 T1507 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 350168 T1507 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 350168 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 350171 T1507 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 350172 T1507 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 350174 T1530 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1
[junit4] 2> 350174 T1530 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1/'
[junit4] 2> 350192 T1530 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 350208 T1530 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 350208 T1530 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 350211 T1530 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 350214 T1530 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 350214 T1530 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 350214 T1530 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1/data/
[junit4] 2> 350215 T1530 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 350215 T1530 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-1377974455252/slave/collection1/data
[junit4] 2> 350215 T1530 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-1377974455252/slave/collection1/data/index/
[junit4] 2> 350216 T1530 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-1377974455252/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 350216 T1530 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-1377974455252/slave/collection1/data/index
[junit4] 2> 350217 T1530 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542494337, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 350218 T1530 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3538c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebaa9d),segFN=segments_1,generation=1}
[junit4] 2> 350218 T1530 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350219 T1530 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 350219 T1530 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 350219 T1530 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 350220 T1530 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 350220 T1530 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 350220 T1530 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 350220 T1530 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 350221 T1530 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 350223 T1530 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 350224 T1530 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 350224 T1530 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=7.0966796875, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=26.7651242959393, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2544053572133088
[junit4] 2> 350224 T1530 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3538c4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebaa9d),segFN=segments_1,generation=1}
[junit4] 2> 350225 T1530 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350225 T1530 oass.SolrIndexSearcher.<init> Opening Searcher@1071dfe main
[junit4] 2> 350236 T1530 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 350237 T1530 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 350237 T1531 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1071dfe main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 350261 T1507 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 350262 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 350262 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 350287 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=33513053
[junit4] 2> 350288 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7c1953
[junit4] 2> 350289 T1507 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> 350289 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 350290 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 350290 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 350291 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 350292 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 350292 T1507 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-1377974455252/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-1377974455252/slave/collection1/data;done=false>>]
[junit4] 2> 350293 T1507 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-1377974455252/slave/collection1/data
[junit4] 2> 350293 T1507 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-1377974455252/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-1377974455252/slave/collection1/data/index;done=false>>]
[junit4] 2> 350294 T1507 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-1377974455252/slave/collection1/data/index
[junit4] 2> 350294 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 350376 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=10874868
[junit4] 2> 350377 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f97e56
[junit4] 2> 350377 T1507 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> 350378 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 350378 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 350379 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 350380 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 350380 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 350381 T1507 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-1377974455022/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-1377974455022/master/collection1/data/index;done=false>>]
[junit4] 2> 350381 T1507 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-1377974455022/master/collection1/data/index
[junit4] 2> 350382 T1507 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-1377974455022/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-1377974455022/master/collection1/data;done=false>>]
[junit4] 2> 350382 T1507 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-1377974455022/master/collection1/data
[junit4] 2> 350383 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 350436 T1507 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 350438 T1507 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:41255
[junit4] 2> 350439 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 350440 T1507 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 350440 T1507 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master
[junit4] 2> 350441 T1507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/'
[junit4] 2> 350451 T1507 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-1377974455022/master/solr.xml
[junit4] 2> 350457 T1507 oasc.CoreContainer.<init> New CoreContainer 19469731
[junit4] 2> 350457 T1507 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/]
[junit4] 2> 350458 T1507 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 350458 T1507 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 350458 T1507 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 350459 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 350459 T1507 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 350459 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 350459 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 350460 T1507 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 350460 T1507 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 350460 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 350462 T1507 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 350463 T1507 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 350471 T1543 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1
[junit4] 2> 350471 T1543 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/'
[junit4] 2> 350484 T1543 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 350491 T1543 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 350492 T1543 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 350494 T1543 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 350497 T1543 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 350497 T1543 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 350498 T1543 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/data/
[junit4] 2> 350498 T1543 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 350499 T1543 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-1377974455022/master/collection1/data
[junit4] 2> 350499 T1543 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-1377974455022/master/collection1/data/index/
[junit4] 2> 350499 T1543 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-1377974455022/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 350500 T1543 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-1377974455022/master/collection1/data/index
[junit4] 2> 350500 T1543 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542494337, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 350501 T1543 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_1,generation=1}
[junit4] 2> 350501 T1543 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350503 T1543 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 350503 T1543 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 350503 T1543 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 350503 T1543 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 350503 T1543 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 350504 T1543 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 350504 T1543 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 350505 T1543 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 350506 T1543 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 350506 T1543 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 350507 T1543 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=7.0966796875, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=26.7651242959393, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2544053572133088
[junit4] 2> 350507 T1543 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_1,generation=1}
[junit4] 2> 350507 T1543 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350508 T1543 oass.SolrIndexSearcher.<init> Opening Searcher@1b8b750 main
[junit4] 2> 350508 T1543 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 350508 T1543 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 350509 T1544 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b8b750 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 350509 T1543 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 350510 T1507 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 350510 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 350511 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2901 name=collection1 org.apache.solr.core.SolrCore@1e78a30
[junit4] 2> 350538 T1540 C2901 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 350539 T1540 C2901 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2
[junit4] 2> 350541 T1541 C2901 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 350543 T1541 C2901 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_2,generation=2}
[junit4] 2> 350543 T1541 C2901 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 350544 T1541 C2901 oass.SolrIndexSearcher.<init> Opening Searcher@19727df main
[junit4] 2> 350544 T1541 C2901 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 350544 T1544 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19727df main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 350545 T1541 C2901 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 350551 T1542 C2901 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> 350553 T1507 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 350565 T1507 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55542
[junit4] 2> 350566 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 350566 T1507 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 350567 T1507 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave
[junit4] 2> 350567 T1507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/'
[junit4] 2> 350576 T1507 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-1377974455252/slave/solr.xml
[junit4] 2> 350582 T1507 oasc.CoreContainer.<init> New CoreContainer 3149477
[junit4] 2> 350583 T1507 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/]
[junit4] 2> 350583 T1507 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 350584 T1507 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 350584 T1507 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 350584 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 350584 T1507 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 350585 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 350585 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 350585 T1507 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 350586 T1507 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 350586 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 350588 T1507 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 350589 T1507 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 350590 T1555 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1
[junit4] 2> 350590 T1555 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1/'
[junit4] 2> 350605 T1555 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 350620 T1555 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 350621 T1555 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 350623 T1555 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 350625 T1555 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 350626 T1555 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 350626 T1555 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455252/slave/collection1/data/
[junit4] 2> 350626 T1555 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 350627 T1555 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-1377974455252/slave/collection1/data
[junit4] 2> 350627 T1555 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-1377974455252/slave/collection1/data/index/
[junit4] 2> 350628 T1555 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-1377974455252/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 350628 T1555 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-1377974455252/slave/collection1/data/index
[junit4] 2> 350629 T1555 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542494337, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 350630 T1555 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@50fd48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13978d4),segFN=segments_1,generation=1}
[junit4] 2> 350630 T1555 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350631 T1555 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 350631 T1555 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 350631 T1555 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 350632 T1555 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 350632 T1555 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 350632 T1555 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 350632 T1555 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 350634 T1555 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 350635 T1555 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 350635 T1555 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 350635 T1555 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=7.0966796875, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=26.7651242959393, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2544053572133088
[junit4] 2> 350636 T1555 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@50fd48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13978d4),segFN=segments_1,generation=1}
[junit4] 2> 350636 T1555 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 350636 T1555 oass.SolrIndexSearcher.<init> Opening Searcher@a18077 main
[junit4] 2> 350644 T1555 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 350645 T1556 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a18077 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 350645 T1555 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 350645 T1507 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 350646 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 350646 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2902 name=collection1 org.apache.solr.core.SolrCore@1aa669a
[junit4] 2> 350651 T1552 C2902 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> ASYNC NEW_CORE C2903 name=collection1 org.apache.solr.core.SolrCore@1e78a30
[junit4] 2> 350653 T1539 C2903 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 350656 T1540 C2903 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 350658 T1541 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1
[junit4] 2> 350658 T1541 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/'
[junit4] 2> 350674 T1541 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 350690 T1541 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 350691 T1541 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 350693 T1541 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 350695 T1541 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 350696 T1541 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974455022/master/collection1/data/
[junit4] 2> 350696 T1541 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 350696 T1541 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-1377974455022/master/collection1/data/index/
[junit4] 2> 350697 T1541 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 350697 T1541 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 350698 T1541 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 350698 T1541 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 350698 T1541 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 350698 T1541 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 350699 T1541 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 350700 T1541 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 350700 T1541 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 350701 T1541 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 350701 T1541 oass.SolrIndexSearcher.<init> Opening Searcher@1b3b833 main
[junit4] 2> 350701 T1541 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 350702 T1541 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 350702 T1541 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 350702 T1559 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b3b833 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 350702 T1541 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 350702 T1541 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 350703 T1541 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=58.5888671875, floorSegmentMB=1.4404296875, forceMergeDeletesPctAllowed=24.786372799445143, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 350704 T1541 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_2,generation=2}
[junit4] 2> 350704 T1541 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 350704 T1541 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 350705 T1541 oass.SolrIndexSearcher.<init> Opening Searcher@1e2eb3c main
[junit4] 2> 350705 T1559 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e2eb3c main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 350705 T1541 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 350706 T1541 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1e78a30
[junit4] 2> 350706 T1541 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 350707 T1541 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 350707 T1541 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=49
[junit4] 2> ASYNC NEW_CORE C2904 name=collection1 org.apache.solr.core.SolrCore@7d48e5
[junit4] 2> 350711 T1542 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 350713 T1539 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 350717 T1540 C2904 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 350720 T1541 C2904 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 350722 T1542 C2904 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 350724 T1542 C2904 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@122a911 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@781705),segFN=segments_3,generation=3}
[junit4] 2> 350724 T1542 C2904 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 350725 T1542 C2904 oass.SolrIndexSearcher.<init> Opening Searcher@4979eb main
[junit4] 2> 350726 T1542 C2904 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 350726 T1559 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4979eb main{StandardDirectoryReader(segments_3:4:nrt _0(4.5):C2)}
[junit4] 2> 350726 T1542 C2904 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 350729 T1539 C2904 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> ASYNC NEW_CORE C2905 name=collection1 org.apache.solr.core.SolrCore@1aa669a
[junit4] 2> 350732 T1553 C2905 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 350733 T1507 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 350835 T1554 C2905 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 350836 T1507 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 350861 T1541 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 350862 T1557 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 350862 T1557 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 350862 T1557 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 350864 T1542 C2904 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> 350865 T1557 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 350866 T1557 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-1377974455252/slave/collection1/data/index.20130831194056005
[junit4] 2> 350866 T1557 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@14e1d23 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@178c9f0) fullCopy=false
[junit4] 2> 350868 T1539 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350871 T1540 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350874 T1541 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350876 T1542 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350879 T1539 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350882 T1540 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350884 T1541 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350887 T1542 C2904 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2906 name=collection1 org.apache.solr.core.SolrCore@7d48e5
[junit4] 2> 350900 T1539 C2906 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350903 T1540 C2906 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 350904 T1557 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 350904 T1557 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 350905 T1557 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 350905 T1557 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 350906 T1557 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 350906 T1557 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=37.50390625, floorSegmentMB=0.931640625, forceMergeDeletesPctAllowed=12.92600409729272, segmentsPerTier=18.0, maxCFSSegmentSizeMB=0.9560546875, noCFSRatio=0.8574290045615245
[junit4] 2> 350912 T1557 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@50fd48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13978d4),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@50fd48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13978d4),segFN=segments_3,generation=3}
[junit4] 2> 350912 T1557 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 350912 T1557 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 350913 T1557 oass.SolrIndexSearcher.<init> Opening Searcher@1209412 main
[junit4] 2> 350913 T1556 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1209412 main{StandardDirectoryReader(segments_3:4:nrt _0(4.5):C2)}
[junit4] 2> 350913 T1557 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-1377974455252/slave/collection1/data/index.20130831194056005 [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-1377974455252/slave/collection1/data/index.20130831194056005;done=true>>]
[junit4] 2> 350913 T1557 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-1377974455252/slave/collection1/data/index.20130831194056005
[junit4] 2> ASYNC NEW_CORE C2907 name=collection1 org.apache.solr.core.SolrCore@1aa669a
[junit4] 2> 350938 T1551 C2907 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> 350939 T1507 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 350961 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=19469731
[junit4] 2> 350962 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7d48e5
[junit4] 2> 350962 T1507 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> 350963 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 350963 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 350964 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 350965 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 350965 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 350965 T1507 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-1377974455022/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-1377974455022/master/collection1/data;done=false>>]
[junit4] 2> 350966 T1507 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-1377974455022/master/collection1/data
[junit4] 2> 350966 T1507 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-1377974455022/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-1377974455022/master/collection1/data/index;done=false>>]
[junit4] 2> 350966 T1507 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-1377974455022/master/collection1/data/index
[junit4] 2> 350967 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 351040 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3149477
[junit4] 2> 351041 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1aa669a
[junit4] 2> 351041 T1507 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> 351042 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 351042 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 351043 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 351044 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 351044 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 351044 T1507 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-1377974455252/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-1377974455252/slave/collection1/data/index;done=false>>]
[junit4] 2> 351045 T1507 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-1377974455252/slave/collection1/data/index
[junit4] 2> 351045 T1507 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-1377974455252/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-1377974455252/slave/collection1/data;done=false>>]
[junit4] 2> 351045 T1507 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-1377974455252/slave/collection1/data
[junit4] 2> 351046 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 351103 T1507 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 351107 T1507 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 351109 T1507 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49644
[junit4] 2> 351109 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 351110 T1507 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 351110 T1507 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master
[junit4] 2> 351110 T1507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master/'
[junit4] 2> 351120 T1507 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-1377974456244/master/solr.xml
[junit4] 2> 351127 T1507 oasc.CoreContainer.<init> New CoreContainer 30423027
[junit4] 2> 351128 T1507 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master/]
[junit4] 2> 351128 T1507 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 351129 T1507 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 351129 T1507 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 351129 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 351130 T1507 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 351130 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 351130 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 351130 T1507 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 351131 T1507 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 351131 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 351134 T1507 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 351134 T1507 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 351135 T1570 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master/collection1
[junit4] 2> 351135 T1570 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master/collection1/'
[junit4] 2> 351159 T1570 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 351164 T1570 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 351164 T1570 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 351166 T1570 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 351168 T1570 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 351169 T1570 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 351169 T1570 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456244/master/collection1/data/
[junit4] 2> 351169 T1570 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 351170 T1570 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-1377974456244/master/collection1/data
[junit4] 2> 351170 T1570 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-1377974456244/master/collection1/data/index/
[junit4] 2> 351170 T1570 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-1377974456244/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 351170 T1570 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-1377974456244/master/collection1/data/index
[junit4] 2> 351171 T1570 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542494337, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 351171 T1570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ef6431 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e284b0),segFN=segments_1,generation=1}
[junit4] 2> 351171 T1570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 351172 T1570 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 351172 T1570 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 351172 T1570 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 351172 T1570 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 351172 T1570 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 351173 T1570 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 351173 T1570 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 351174 T1570 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 351175 T1570 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 351175 T1570 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 351175 T1570 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=7.0966796875, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=26.7651242959393, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2544053572133088
[junit4] 2> 351176 T1570 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ef6431 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e284b0),segFN=segments_1,generation=1}
[junit4] 2> 351176 T1570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 351176 T1570 oass.SolrIndexSearcher.<init> Opening Searcher@13bcfd0 main
[junit4] 2> 351176 T1570 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 351176 T1570 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 351177 T1570 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 351177 T1571 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13bcfd0 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 351177 T1507 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 351177 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 351178 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 351183 T1507 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 351185 T1507 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50519
[junit4] 2> 351185 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 351186 T1507 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 351186 T1507 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave
[junit4] 2> 351187 T1507 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave/'
[junit4] 2> 351198 T1507 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-1377974456320/slave/solr.xml
[junit4] 2> 351214 T1507 oasc.CoreContainer.<init> New CoreContainer 21936434
[junit4] 2> 351215 T1507 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave/]
[junit4] 2> 351215 T1507 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 351216 T1507 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 351216 T1507 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 351216 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 351217 T1507 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 351217 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 351218 T1507 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 351218 T1507 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 351218 T1507 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 351219 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 351222 T1507 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 351223 T1507 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 351225 T1582 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave/collection1
[junit4] 2> 351225 T1582 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave/collection1/'
[junit4] 2> 351243 T1582 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 351248 T1582 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 351249 T1582 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 351251 T1582 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 351261 T1582 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 351262 T1582 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 351262 T1582 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377974456320/slave/collection1/data/
[junit4] 2> 351262 T1582 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 351262 T1582 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-1377974456320/slave/collection1/data
[junit4] 2> 351263 T1582 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-1377974456320/slave/collection1/data/index/
[junit4] 2> 351263 T1582 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-1377974456320/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 351263 T1582 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-1377974456320/slave/collection1/data/index
[junit4] 2> 351264 T1582 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542494337, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 351264 T1582 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@859bdb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@114338),segFN=segments_1,generation=1}
[junit4] 2> 351264 T1582 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 351265 T1582 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 351265 T1582 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 351265 T1582 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 351265 T1582 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 351265 T1582 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 351266 T1582 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 351266 T1582 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 351267 T1582 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 351267 T1582 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 351267 T1582 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 351268 T1582 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=7.0966796875, floorSegmentMB=0.9560546875, forceMergeDeletesPctAllowed=26.7651242959393, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2544053572133088
[junit4] 2> 351268 T1582 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@859bdb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@114338),segFN=segments_1,generation=1}
[junit4] 2> 351268 T1582 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 351268 T1582 oass.SolrIndexSearcher.<init> Opening Searcher@22e3c1 main
[junit4] 2> 351269 T1582 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 351269 T1582 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 351269 T1583 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@22e3c1 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 351269 T1507 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 351270 T1507 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 351270 T1507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 351296 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21936434
[junit4] 2> 351296 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@bf7733
[junit4] 2> 351297 T1507 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> 351297 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 351297 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 351298 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 351299 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 351299 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 351299 T1507 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-1377974456320/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-1377974456320/slave/collection1/data/index;done=false>>]
[junit4] 2> 351300 T1507 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-1377974456320/slave/collection1/data/index
[junit4] 2> 351300 T1507 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-1377974456320/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-1377974456320/slave/collection1/data;done=false>>]
[junit4] 2> 351300 T1507 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-1377974456320/slave/collection1/data
[junit4] 2> 351301 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> ASYNC NEW_CORE C2908 name=collection1 org.apache.solr.core.SolrCore@1c22a0b
[junit4]
[...truncated too long message...]
97 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 415964 T2197 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12d5d52
[junit4] 2> 415964 T2197 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> 415964 T2197 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 415965 T2198 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13e515c main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C1)}
[junit4] 2> ASYNC NEW_CORE C3024 name=collection1 org.apache.solr.core.SolrCore@1251cde
[junit4] 2> 415974 T2190 C3024 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 415976 T1507 oas.SolrTestCaseJ4.tearDown ###Ending doTestIndexAndConfigReplication
[junit4] 2> 416000 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=28507818
[junit4] 2> 416001 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@81c4f1
[junit4] 2> 416001 T1507 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 416002 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 416002 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 416003 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 416004 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 416004 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 416005 T1507 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-1377974518723/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-1377974518723/master/collection1/data;done=false>>]
[junit4] 2> 416005 T1507 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-1377974518723/master/collection1/data
[junit4] 2> 416006 T1507 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-1377974518723/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-1377974518723/master/collection1/data/index;done=false>>]
[junit4] 2> 416006 T1507 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-1377974518723/master/collection1/data/index
[junit4] 2> 416007 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 416080 T1507 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=5466962
[junit4] 2> 416080 T1507 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1251cde
[junit4] 2> 416081 T1507 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> 416081 T1507 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 416082 T1507 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 416082 T1507 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 416083 T1507 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 416083 T1507 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 416084 T1507 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-1377974518799/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-1377974518799/slave/collection1/data/index;done=false>>]
[junit4] 2> 416084 T1507 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-1377974518799/slave/collection1/data/index
[junit4] 2> 416085 T1507 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-1377974518799/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-1377974518799/slave/collection1/data;done=false>>]
[junit4] 2> 416085 T1507 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-1377974518799/slave/collection1/data
[junit4] 2> 416086 T1507 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 416142 T1507 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=DefaultSimilarity, locale=in, timezone=Europe/Jersey
[junit4] 2> NOTE: Linux 3.2.0-52-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=68333608,total=181710848
[junit4] 2> NOTE: All tests run in this JVM: [CacheHeaderTest, SolrInfoMBeanTest, CSVRequestHandlerTest, TestBadConfig, TestShardHandlerFactory, ScriptEngineTest, TestGroupingSearch, ShowFileRequestHandlerTest, OverseerTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, TestStressVersions, HdfsBasicDistributedZkTest, DocumentBuilderTest, TestDynamicFieldCollectionResource, UUIDFieldTest, ExternalFileFieldSortTest, SpellPossibilityIteratorTest, TestLRUCache, TestExtendedDismaxParser, QueryEqualityTest, TestPostingsSolrHighlighter, TestRealTimeGet, TestUniqueKeyFieldResource, SolrTestCaseJ4Test, TestJmxMonitoredMap, SOLR749Test, CircularListTest, MBeansHandlerTest, SyncSliceTest, SliceStateTest, LegacyHTMLStripCharFilterTest, TestSchemaSimilarityResource, TestRemoteStreaming, SimpleFacetsTest, ResponseLogComponentTest, TestValueSourceCache, MoreLikeThisHandlerTest, ClusterStateUpdateTest, TestSort, DocValuesMissingTest, TestSolrQueryParserDefaultOperatorResource, CopyFieldTest, ParsingFieldUpdateProcessorsTest, TestSystemIdResolver, TestBM25SimilarityFactory, ReturnFieldsTest, SolrCoreTest, HdfsLockFactoryTest, TestFaceting, TestConfig, TestTrie, CoreAdminHandlerTest, BadComponentTest, TestDocumentBuilder, HdfsBasicDistributedZk2Test, LeaderElectionTest, QueryParsingTest, UnloadDistributedZkTest, DateMathParserTest, AddSchemaFieldsUpdateProcessorFactoryTest, AutoCommitTest, URLClassifyProcessorTest, TestSolrQueryParser, SchemaVersionSpecificBehaviorTest, TestLMDirichletSimilarityFactory, SolrXmlInZkTest, TestSuggestSpellingConverter, TermVectorComponentTest, DocValuesTest, ResourceLoaderTest, HdfsCollectionsAPIDistributedZkTest, TestReplicationHandler]
[junit4] Completed on J0 in 66.32s, 14 tests, 1 failure <<< FAILURES!
[...truncated 607 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:402: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:382: 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:455: 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: 325 suites, 1415 tests, 1 failure, 39 ignored (6 assumptions)
Total time: 39 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b102 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure