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 2014/05/28 20:47:20 UTC
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.7.0) - Build # 1567 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1567/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Could not remove the following files (in the order of attempts): /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300229309/master/collection1/data/snapshot.cekgerxxxeswd/_0.cfe
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300229309/master/collection1/data/snapshot.cekgerxxxeswd/_0.cfe
at __randomizedtesting.SeedInfo.seed([EF1A47F31D57FC6F:AE9167963AE90F20]:0)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1554)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11394 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-EF1A47F31D57FC6F-001/init-core-data-001
[junit4] 2> 3050618 T8632 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 3050634 T8632 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3050638 T8632 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56511
[junit4] 2> 3050639 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3050640 T8632 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3050640 T8632 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master
[junit4] 2> 3050641 T8632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/'
[junit4] 2> 3050680 T8632 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/solr.xml
[junit4] 2> 3050782 T8632 oasc.CoreContainer.<init> New CoreContainer 1986789163
[junit4] 2> 3050782 T8632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/]
[junit4] 2> 3050784 T8632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3050785 T8632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3050785 T8632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3050786 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3050786 T8632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3050787 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3050787 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3050787 T8632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3050788 T8632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3050788 T8632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3050788 T8632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3050788 T8632 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3050801 T8642 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/'
[junit4] 2> 3050874 T8642 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 3050951 T8642 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3050952 T8642 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3050959 T8642 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3050983 T8642 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3050987 T8642 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/
[junit4] 2> 3050987 T8642 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3050987 T8642 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/
[junit4] 2> 3050987 T8642 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 3050988 T8642 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data
[junit4] 2> 3050988 T8642 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index/
[junit4] 2> 3050989 T8642 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 3050989 T8642 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index
[junit4] 2> 3050989 T8642 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=38, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3050990 T8642 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5a7b9c22 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@605abcd4),segFN=segments_1,generation=1}
[junit4] 2> 3050990 T8642 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3050992 T8642 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3050992 T8642 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3050993 T8642 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3050993 T8642 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3050993 T8642 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3050993 T8642 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3050994 T8642 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3051006 T8642 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3051010 T8642 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3051010 T8642 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3051010 T8642 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12989110763407397]
[junit4] 2> 3051011 T8642 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5a7b9c22 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@605abcd4),segFN=segments_1,generation=1}
[junit4] 2> 3051011 T8642 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3051011 T8642 oass.SolrIndexSearcher.<init> Opening Searcher@20fd3be1[collection1] main
[junit4] 2> 3051012 T8642 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/conf
[junit4] 2> 3051012 T8642 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/conf/}
[junit4] 2> 3051012 T8642 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/conf
[junit4] 2> 3051013 T8642 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3051014 T8642 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3051014 T8642 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3051014 T8642 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 3051014 T8642 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 3051015 T8642 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3051015 T8643 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20fd3be1[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3051016 T8632 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3051017 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3051029 T8632 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3051036 T8632 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56512
[junit4] 2> 3051037 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3051039 T8632 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3051039 T8632 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave
[junit4] 2> 3051040 T8632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/'
[junit4] 2> 3051065 T8632 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/solr.xml
[junit4] 2> 3051146 T8632 oasc.CoreContainer.<init> New CoreContainer 1383729879
[junit4] 2> 3051147 T8632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/]
[junit4] 2> 3051149 T8632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3051149 T8632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3051150 T8632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3051150 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3051150 T8632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3051151 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3051151 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3051151 T8632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3051152 T8632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3051153 T8632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3051153 T8632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3051153 T8632 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3051160 T8654 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/'
[junit4] 2> 3051233 T8654 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 3051315 T8654 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3051317 T8654 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3051324 T8654 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3051386 T8654 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3051388 T8654 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/
[junit4] 2> 3051388 T8654 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3051388 T8654 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/
[junit4] 2> 3051388 T8654 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 3051389 T8654 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data
[junit4] 2> 3051390 T8654 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index/
[junit4] 2> 3051390 T8654 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 3051391 T8654 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index
[junit4] 2> 3051391 T8654 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=38, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3051393 T8654 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@53c6270c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e13fce1),segFN=segments_1,generation=1}
[junit4] 2> 3051393 T8654 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3051394 T8654 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3051394 T8654 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3051395 T8654 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3051395 T8654 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3051395 T8654 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3051396 T8654 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3051397 T8654 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3051399 T8654 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3051403 T8654 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3051404 T8654 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3051404 T8654 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12989110763407397]
[junit4] 2> 3051405 T8654 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@53c6270c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e13fce1),segFN=segments_1,generation=1}
[junit4] 2> 3051405 T8654 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3051406 T8654 oass.SolrIndexSearcher.<init> Opening Searcher@c68be32[collection1] main
[junit4] 2> 3051406 T8654 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf
[junit4] 2> 3051406 T8654 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf/}
[junit4] 2> 3051407 T8654 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf
[junit4] 2> 3051407 T8654 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3051407 T8654 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3051407 T8654 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3051408 T8654 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 3051409 T8654 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3051409 T8655 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c68be32[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3051411 T8632 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3051411 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3051428 T8632 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1383729879
[junit4] 2> 3051430 T8632 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1b447b
[junit4] 2> 3051431 T8632 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> 3051431 T8632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 3051431 T8632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 3051432 T8632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 3051433 T8632 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 3051433 T8632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3051434 T8632 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index;done=false>>]
[junit4] 2> 3051434 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index
[junit4] 2> 3051434 T8632 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data;done=false>>]
[junit4] 2> 3051434 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data
[junit4] 2> 3051435 T8632 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 3051488 T8632 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3051492 T8632 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56513
[junit4] 2> 3051494 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3051494 T8632 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3051494 T8632 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave
[junit4] 2> 3051495 T8632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/'
[junit4] 2> 3051518 T8632 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/solr.xml
[junit4] 2> 3051616 T8632 oasc.CoreContainer.<init> New CoreContainer 1344984883
[junit4] 2> 3051617 T8632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/]
[junit4] 2> 3051620 T8632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3051620 T8632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3051621 T8632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3051622 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3051622 T8632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3051622 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3051622 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3051623 T8632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3051623 T8632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3051624 T8632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3051624 T8632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3051625 T8632 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3051633 T8667 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/'
[junit4] 2> 3051709 T8667 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 3051783 T8667 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3051783 T8667 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3051794 T8667 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3051824 T8667 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3051827 T8667 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/
[junit4] 2> 3051827 T8667 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3051827 T8667 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/
[junit4] 2> 3051828 T8667 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 3051829 T8667 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data
[junit4] 2> 3051829 T8667 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index/
[junit4] 2> 3051830 T8667 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 3051831 T8667 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index
[junit4] 2> 3051831 T8667 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=38, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3051832 T8667 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2690682c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4afd81f5),segFN=segments_1,generation=1}
[junit4] 2> 3051832 T8667 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3051833 T8667 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3051834 T8667 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3051834 T8667 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3051834 T8667 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3051834 T8667 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3051835 T8667 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3051835 T8667 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3051838 T8667 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3051843 T8667 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3051843 T8667 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3051844 T8667 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12989110763407397]
[junit4] 2> 3051844 T8667 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2690682c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4afd81f5),segFN=segments_1,generation=1}
[junit4] 2> 3051845 T8667 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3051845 T8667 oass.SolrIndexSearcher.<init> Opening Searcher@129f3e[collection1] main
[junit4] 2> 3051845 T8667 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf
[junit4] 2> 3051845 T8667 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf/}
[junit4] 2> 3051846 T8667 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf
[junit4] 2> 3051846 T8667 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3051846 T8667 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3051847 T8667 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3051847 T8667 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 3051847 T8667 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3051847 T8668 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@129f3e[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3051848 T8632 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3051849 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3051868 T8632 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1986789163
[junit4] 2> 3051871 T8632 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5f067e19
[junit4] 2> 3051872 T8632 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> 3051872 T8632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 3051873 T8632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 3051874 T8632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 3051875 T8632 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 3051876 T8632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3051877 T8632 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data;done=false>>]
[junit4] 2> 3051878 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data
[junit4] 2> 3051879 T8632 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index;done=false>>]
[junit4] 2> 3051880 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index
[junit4] 2> 3051882 T8632 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 3051940 T8632 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3051943 T8632 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56514
[junit4] 2> 3051944 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3051945 T8632 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3051945 T8632 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master
[junit4] 2> 3051946 T8632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/'
[junit4] 2> 3051969 T8632 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/solr.xml
[junit4] 2> 3052047 T8632 oasc.CoreContainer.<init> New CoreContainer 642162911
[junit4] 2> 3052048 T8632 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/]
[junit4] 2> 3052050 T8632 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3052050 T8632 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3052050 T8632 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3052050 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3052051 T8632 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3052052 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3052052 T8632 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3052052 T8632 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3052052 T8632 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3052054 T8632 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3052054 T8632 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3052055 T8632 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3052061 T8679 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/'
[junit4] 2> 3052135 T8679 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 3052219 T8679 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3052220 T8679 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3052228 T8679 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3052250 T8679 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3052252 T8679 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/
[junit4] 2> 3052252 T8679 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3052252 T8679 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/
[junit4] 2> 3052253 T8679 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 3052253 T8679 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data
[junit4] 2> 3052253 T8679 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index/
[junit4] 2> 3052254 T8679 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 3052255 T8679 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/data/index
[junit4] 2> 3052256 T8679 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=38, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3052257 T8679 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@25a30edb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@503008b9),segFN=segments_1,generation=1}
[junit4] 2> 3052257 T8679 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3052258 T8679 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3052258 T8679 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3052259 T8679 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3052259 T8679 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3052259 T8679 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3052259 T8679 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3052260 T8679 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3052262 T8679 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3052267 T8679 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3052267 T8679 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3052268 T8679 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12989110763407397]
[junit4] 2> 3052269 T8679 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@25a30edb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@503008b9),segFN=segments_1,generation=1}
[junit4] 2> 3052269 T8679 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3052270 T8679 oass.SolrIndexSearcher.<init> Opening Searcher@70bb5a3d[collection1] main
[junit4] 2> 3052270 T8679 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/conf
[junit4] 2> 3052270 T8679 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/conf/}
[junit4] 2> 3052271 T8679 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207599/master/collection1/conf
[junit4] 2> 3052271 T8679 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3052271 T8679 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3052271 T8679 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3052272 T8679 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 3052272 T8679 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 3052272 T8680 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@70bb5a3d[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3052272 T8679 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3052274 T8632 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3052275 T8632 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1981 name=collection1 org.apache.solr.core.SolrCore@73284623
[junit4] 2> 3052280 T8673 C1981 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3052280 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C1982 name=collection1 org.apache.solr.core.SolrCore@95d1d4
[junit4] 2> 3052284 T8662 C1982 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3052285 T8662 C1982 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 3052289 T8663 C1982 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3052290 T8663 C1982 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2690682c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4afd81f5),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2690682c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4afd81f5),segFN=segments_2,generation=2}
[junit4] 2> 3052292 T8663 C1982 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3052293 T8663 C1982 oass.SolrIndexSearcher.<init> Opening Searcher@444d0de4[collection1] main
[junit4] 2> 3052294 T8663 C1982 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3052294 T8668 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@444d0de4[collection1] main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 3052295 T8663 C1982 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&wt=javabin&version=2&waitSearcher=true} {commit=} 0 7
[junit4] 2> 3052303 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 2
[junit4] 2> 3052306 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 3052309 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 3052312 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 3052315 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 3052318 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 3052321 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 3052324 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4] 2> 3052327 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 3052330 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 3052333 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 3052337 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 1
[junit4] 2> 3052339 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 3052343 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 1
[junit4] 2> 3052346 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 1
[junit4] 2> 3052349 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 3052353 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 1
[junit4] 2> 3052357 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 3052360 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 3052363 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 3052366 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 3052370 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 1
[junit4] 2> 3052373 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 3052377 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 3052380 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 3052385 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4] 2> 3052388 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4] 2> 3052394 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 1
[junit4] 2> 3052399 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 1
[junit4] 2> 3052404 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 3052409 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 3052414 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 3052419 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 3052425 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 3052430 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 3052434 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 3052440 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 3052445 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 3052449 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 3052453 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 3052460 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 3052465 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 3052471 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 3052476 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 3052481 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4] 2> 3052487 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 1
[junit4] 2> 3052491 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 3052495 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 3052501 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 3052506 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 0
[junit4] 2> 3052511 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4] 2> 3052515 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 3052521 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 1
[junit4] 2> 3052526 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 3052531 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 0
[junit4] 2> 3052535 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4] 2> 3052541 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4] 2> 3052546 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4] 2> 3052550 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4] 2> 3052556 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 1
[junit4] 2> 3052560 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 1
[junit4] 2> 3052565 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 1
[junit4] 2> 3052570 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4] 2> 3052575 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4] 2> 3052583 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 0
[junit4] 2> 3052588 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 0
[junit4] 2> 3052592 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4] 2> 3052597 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 0
[junit4] 2> 3052603 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 3052607 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 3052613 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 1
[junit4] 2> 3052618 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 1
[junit4] 2> 3052622 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 0
[junit4] 2> 3052626 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4] 2> 3052631 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4] 2> 3052636 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4] 2> 3052642 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 0
[junit4] 2> 3052647 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4] 2> 3052651 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4] 2> 3052656 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 1
[junit4] 2> 3052662 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4] 2> 3052666 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4] 2> 3052671 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4] 2> 3052678 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 0
[junit4] 2> 3052683 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 3052687 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4] 2> 3052692 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 0
[junit4] 2> 3052698 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4] 2> 3052704 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4] 2> 3052709 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4] 2> 3052715 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 0
[junit4] 2> 3052721 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4] 2> 3052727 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 3052731 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4] 2> 3052735 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 1
[junit4] 2> 3052738 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4] 2> 3052740 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 3052743 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4] 2> 3052746 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 0
[junit4] 2> 3052749 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4] 2> 3052752 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4] 2> 3052757 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4] 2> 3052760 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4] 2> 3052763 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 3052765 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 3052768 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4] 2> 3052771 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4] 2> 3052775 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4] 2> 3052779 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 0
[junit4] 2> 3052785 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4] 2> 3052790 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4] 2> 3052793 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4] 2> 3052797 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4] 2> 3052801 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4] 2> 3052805 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 3052808 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 0
[junit4] 2> 3052811 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4] 2> 3052815 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 1
[junit4] 2> 3052817 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 0
[junit4] 2> 3052820 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 3052823 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4] 2> 3052826 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4] 2> 3052830 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4] 2> 3052834 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 3052837 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 3052839 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 0
[junit4] 2> 3052843 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4] 2> 3052847 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 1
[junit4] 2> 3052851 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4] 2> 3052854 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 1
[junit4] 2> 3052857 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 3052860 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 1
[junit4] 2> 3052863 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4] 2> 3052869 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 0
[junit4] 2> 3052874 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 1
[junit4] 2> 3052879 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4] 2> 3052882 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 1
[junit4] 2> 3052885 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4] 2> 3052889 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 1
[junit4] 2> 3052892 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4] 2> 3052896 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 0
[junit4] 2> 3052900 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 0
[junit4] 2> 3052903 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4] 2> 3052908 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4] 2> 3052911 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 3052917 T8678 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 0
[junit4] 2> 3052920 T8676 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4] 2> 3052923 T8673 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4] 2> 3052927 T8674 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 0
[junit4] 2> 3052938 T8675 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4] 2> 3052943 T8677 C1981 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3052946 T8677 C1981 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@25a30edb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@503008b9),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@25a30edb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@503008b9),segFN=segments_2,generation=2}
[junit4] 2> 3052947 T8677 C1981 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3052950 T8677 C1981 oass.SolrIndexSearcher.<init> Opening Searcher@2a430c03[collection1] main
[junit4] 2> 3052951 T8677 C1981 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3052951 T8680 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2a430c03[collection1] main{StandardDirectoryReader(segments_2:4:nrt _0(4.9):c150)}
[junit4] 2> 3052952 T8677 C1981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&wt=javabin&version=2&waitSearcher=true} {commit=} 0 9
[junit4] 2> 3052958 T8678 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=150 status=0 QTime=2
[junit4] 2> 3052963 T8666 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={wt=javabin&version=2&core=collection1&action=status} status=0 QTime=1
[junit4] 2> 3053003 T8682 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 3053005 T8682 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 3053013 T8676 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 3053014 T8682 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 3053015 T8682 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 3053015 T8682 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 3053018 T8673 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&generation=2&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 3053019 T8682 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 3053019 T8682 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index.20140528120329999
[junit4] 2> 3053020 T8682 oash.SnapPuller.isIndexStale WARN File segments_2 expected to be 130 while it is 82
[junit4] 2> 3053020 T8682 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@343be49a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d61be81) fullCopy=true
[junit4] 2> 3053025 T8674 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent} status=0 QTime=1
[junit4] 2> 3053032 T8675 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent} status=0 QTime=0
[junit4] 2> 3053036 T8677 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent} status=0 QTime=0
[junit4] 2> 3053042 T8678 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent} status=0 QTime=0
[junit4] 2> 3053043 T8682 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 3053047 T8682 oash.SnapPuller.downloadConfFiles Starting download of configuration files from master: [{size=1634, name=schema.xml, checksum=1505963717}]
[junit4] 2> 3053059 T8676 C1981 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={cf=schema.xml&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent} status=0 QTime=0
[junit4] 2> 3053079 T8682 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20140528120329999
[junit4] 2> 3053079 T8682 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirectoryWrapper(RAMDirectory@2690682c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4afd81f5)
[junit4] 2> 3053079 T8682 oash.SnapPuller.fetchLatestIndex Configuration files are modified, core will be reloaded
[junit4] 2> 3053080 T8684 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/'
[junit4] 2> 3053241 T8684 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 3053383 T8684 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3053383 T8684 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3053390 T8684 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3053432 T8684 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3053434 T8684 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/
[junit4] 2> 3053434 T8684 oasc.SolrCore.getNewIndexDir New index directory detected: old=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index/ new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index.20140528120329999
[junit4] 2> 3053435 T8684 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/
[junit4] 2> 3053435 T8684 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 3053435 T8684 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index.20140528120329999
[junit4] 2> 3053436 T8684 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3053437 T8684 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3053438 T8684 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3053438 T8684 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3053438 T8684 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3053439 T8684 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3053439 T8684 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3053459 T8684 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3053466 T8684 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3053466 T8684 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3053467 T8684 oass.SolrIndexSearcher.<init> Opening Searcher@2514169c[collection1] main
[junit4] 2> 3053468 T8684 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf
[junit4] 2> 3053468 T8684 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf/}
[junit4] 2> 3053469 T8684 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/conf
[junit4] 2> 3053470 T8684 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3053472 T8684 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3053472 T8684 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3053472 T8684 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 3053473 T8684 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 3053473 T8684 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 3053474 T8684 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 3053473 T8685 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2514169c[collection1] main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 3053487 T8684 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=47.4970703125, floorSegmentMB=1.30859375, forceMergeDeletesPctAllowed=14.276362489992575, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 3053488 T8684 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@343be49a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d61be81),segFN=segments_2,generation=2}
[junit4] 2> 3053488 T8684 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3053488 T8684 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 3053490 T8684 oass.SolrIndexSearcher.<init> Opening Searcher@4ab4a53d[collection1] main
[junit4] 2> 3053491 T8684 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 3053491 T8685 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ab4a53d[collection1] main{StandardDirectoryReader(segments_2:4:nrt _0(4.9):c150)}
[junit4] 2> 3053491 T8682 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirectoryWrapper(RAMDirectory@2690682c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4afd81f5)
[junit4] 2> 3053492 T8682 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 3053493 T8682 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 3053493 T8682 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 3053494 T8682 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8502624904518238]
[junit4] 2> 3053496 T8682 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@343be49a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d61be81),segFN=segments_2,generation=2}
[junit4] 2> 3053496 T8682 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3053497 T8682 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 3053500 T8682 oass.SolrIndexSearcher.<init> Opening Searcher@4238e536[collection1] main
[junit4] 2> 3053500 T8668 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4238e536[collection1] main{StandardDirectoryReader(segments_2:4:nrt _0(4.9):c150)}
[junit4] 2> ASYNC NEW_CORE C1983 name=collection1 org.apache.solr.core.SolrCore@95d1d4
[junit4] 2> 3053501 T8661 C1983 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56514/solr/replication&command=fetchindex} status=0 QTime=499
[junit4] 2> 3053503 T8661 C1983 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index;done=true>>]
[junit4] 2> 3053504 T8661 C1983 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300207998/slave/collection1/data/index
[junit4] 2> 3053505 T8661 C1983 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@95d1d4
[junit4] 2> 3053506 T8661 C1983 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> 3053513 T8661 C1983 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 3053515 T8663 oass.SolrDispatchFilter.handleAdminRequ
[...truncated too long message...]
chedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272021/master/collection1/data;done=false>>]
[junit4] 2> 3118166 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272021/master/collection1/data
[junit4] 2> 3118166 T8632 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 3118237 T8632 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1399706944
[junit4] 2> 3118240 T8632 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@78ccf5c9
[junit4] 2> 3118241 T8632 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> 3118242 T8632 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 3118243 T8632 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 3118243 T8632 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 3118245 T8632 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 3118245 T8632 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3118245 T8632 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272733/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272733/slave/collection1/data;done=false>>]
[junit4] 2> 3118246 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272733/slave/collection1/data
[junit4] 2> 3118247 T8632 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272733/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272733/slave/collection1/data/index;done=false>>]
[junit4] 2> 3118247 T8632 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401300272733/slave/collection1/data/index
[junit4] 2> 3118249 T8632 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 3118317 T8632 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-EF1A47F31D57FC6F-001
[junit4] 2> NOTE: test params are: codec=Lucene46: {newname=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct), name=PostingsFormat(name=Direct)}, docValues:{}, sim=DefaultSimilarity, locale=es_HN, timezone=Mexico/BajaSur
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_55 (64-bit)/cpus=2,threads=1,free=151033336,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DistributedTermsComponentTest, TestWordDelimiterFilterFactory, OutputWriterTest, LoggingHandlerTest, ParsingFieldUpdateProcessorsTest, TestStressUserVersions, BadCopyFieldTest, TestPostingsSolrHighlighter, TestManagedResourceStorage, DistributedExpandComponentTest, BasicDistributedZkTest, InfoHandlerTest, CurrencyFieldOpenExchangeTest, DistribCursorPagingTest, TermsComponentTest, AtomicUpdatesTest, AnalysisAfterCoreReloadTest, TestUniqueKeyFieldResource, DistributedDebugComponentTest, PolyFieldTest, TestManagedResource, TestCSVResponseWriter, PathHierarchyTokenizerFactoryTest, TestDistribDocBasedVersion, TestRecovery, TestNonDefinedSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestCustomSort, TestJmxMonitoredMap, TestRequestStatusCollectionAPI, BJQParserTest, SolrPluginUtilsTest, TestCloudManagedSchemaAddField, SolrIndexSplitterTest, TestCollapseQParserPlugin, TestAddFieldRealTimeGet, RegexBoostProcessorTest, TestJoin, ConnectionManagerTest, TestQuerySenderNoQuery, TestSolrIndexConfig, TestManagedSchemaFieldResource, NumericFieldsTest, HdfsBasicDistributedZk2Test, BasicDistributedZk2Test, TimeZoneUtilsTest, QueryParsingTest, TestCloudInspectUtil, ShardRoutingTest, TestConfig, TestAnalyzedSuggestions, ChaosMonkeyNothingIsSafeTest, CollectionsAPIAsyncDistributedZkTest, LeaderElectionIntegrationTest, TestInitQParser, TermVectorComponentTest, UnloadDistributedZkTest, TestExtendedDismaxParser, TestSchemaNameResource, TestRandomDVFaceting, TestImplicitCoreProperties, TestRandomFaceting, TestXIncludeConfig, HdfsBasicDistributedZkTest, MinimalSchemaTest, TestSolrXmlPersistence, TestSolrJ, StressHdfsTest, TestSolrCoreProperties, PingRequestHandlerTest, DeleteInactiveReplicaTest, CoreContainerCoreInitFailuresTest, TestMiniSolrCloudCluster, TestSearcherReuse, CSVRequestHandlerTest, ShardSplitTest, TestCSVLoader, HardAutoCommitTest, TestSuggestSpellingConverter, TestSerializedLuceneMatchVersion, TestOmitPositions, SoftAutoCommitTest, DocValuesMissingTest, CoreAdminHandlerTest, TestBinaryResponseWriter, TestQueryTypes, TestLazyCores, TestHighFrequencyDictionaryFactory, TestRecoveryHdfs, BinaryUpdateRequestHandlerTest, FastVectorHighlighterTest, TestFuzzyAnalyzedSuggestions, StatelessScriptUpdateProcessorFactoryTest, TestGroupingSearch, DirectSolrConnectionTest, TestUpdate, SearchHandlerTest, PluginInfoTest, DocValuesTest, TestSurroundQueryParser, TestShortCircuitedRequests, ClusterStateUpdateTest, ClusterStateTest, TestFastWriter, MultiThreadedOCPTest, FieldAnalysisRequestHandlerTest, TestSimpleQParserPlugin, TestInfoStreamLogging, QueryElevationComponentTest, FullSolrCloudDistribCmdsTest, TestOverriddenPrefixQueryForCustomFieldType, TestHashPartitioner, TestValueSourceCache, OpenCloseCoreStressTest, BlockCacheTest, TestNumberUtils, QueryResultKeyTest, SpellCheckComponentTest, TestComponentsName, TestAtomicUpdateErrorCases, TestSolrXml, SolrCoreCheckLockOnStartupTest, TestSolrQueryParserResource, ChangedSchemaMergeTest, UpdateParamsTest, TestReload, HighlighterTest, BadComponentTest, TestDistributedMissingSort, SolrCoreTest, MultiTermTest, TestSolrXMLSerializer, TestDynamicFieldCollectionResource, CircularListTest, TestSweetSpotSimilarityFactory, SystemInfoHandlerTest, IndexSchemaTest, AliasIntegrationTest, TestTrie, TestCoreContainer, DistributedSuggestComponentTest, SortByFunctionTest, SolrInfoMBeanTest, HdfsCollectionsAPIDistributedZkTest, TestCopyFieldCollectionResource, CoreAdminRequestStatusTest, TestRealTimeGet, SpellingQueryConverterTest, DateFieldTest, OverseerTest, TestComplexPhraseQParserPlugin, TestFieldTypeCollectionResource, DistributedQueryComponentCustomSortTest, TestDynamicFieldResource, AlternateDirectoryTest, RemoteQueryErrorTest, TestSystemIdResolver, TestQuerySenderListener, RecoveryZkTest, TestRandomMergePolicy, TestPhraseSuggestions, TestMaxScoreQueryParser, TestRestManager, TestExpandComponent, OpenExchangeRatesOrgProviderTest, AddBlockUpdateTest, MoreLikeThisHandlerTest, TestRTGBase, RankQueryTest, TestFunctionQuery, NotRequiredUniqueKeyTest, TermVectorComponentDistributedTest, HighlighterConfigTest, HdfsChaosMonkeySafeLeaderTest, TestSolrQueryParserDefaultOperatorResource, DistributedQueryComponentOptimizationTest, UpdateRequestProcessorFactoryTest, ZkControllerTest, SolrCmdDistributorTest, DistanceFunctionTest, TestZkChroot, TestFieldTypeResource, TestStressLucene, ChaosMonkeySafeLeaderTest, TestPerFieldSimilarity, ConvertedLegacyTest, TestReversedWildcardFilterFactory, CollectionsAPIDistributedZkTest, SyncSliceTest, BasicZkTest, TestReplicationHandler]
[junit4] Completed in 67.76s, 14 tests, 1 error <<< FAILURES!
[...truncated 617 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1297: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:921: There were test failures: 396 suites, 1640 tests, 1 error, 41 ignored (14 assumptions)
Total time: 136 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.8.0) - Build # 1568 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1568/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Could not remove the following files (in the order of attempts): /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/snapshot.rmmxxbeohllmy
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/snapshot.rmmxxbeohllmy
at __randomizedtesting.SeedInfo.seed([4663FFA1657C3442:7E8DFC442C2C70D]:0)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1554)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11213 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-4663FFA1657C3442-001/init-core-data-001
[junit4] 2> 2483478 T9315 oas.SolrTestCaseJ4.setUp ###Starting testNoWriter
[junit4] 2> 2483493 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2483498 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56935
[junit4] 2> 2483500 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2483501 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2483501 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master
[junit4] 2> 2483501 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/'
[junit4] 2> 2483549 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/solr.xml
[junit4] 2> 2483565 T9315 oasc.CoreContainer.<init> New CoreContainer 2005654738
[junit4] 2> 2483566 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/]
[junit4] 2> 2483566 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2483566 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2483567 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2483567 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2483567 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2483569 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2483570 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2483570 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2483576 T9325 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/'
[junit4] 2> 2483602 T9325 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2483608 T9325 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2483609 T9325 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2483612 T9325 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2483619 T9325 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2483619 T9325 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/
[junit4] 2> 2483619 T9325 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2483620 T9325 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/
[junit4] 2> 2483620 T9325 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2483620 T9325 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2483620 T9325 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index/
[junit4] 2> 2483621 T9325 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2483621 T9325 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2483621 T9325 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2483622 T9325 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13a4d3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ccf1b4),segFN=segments_1,generation=1}
[junit4] 2> 2483622 T9325 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483624 T9325 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2483626 T9325 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2483626 T9325 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2483632 T9325 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2483634 T9325 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2483634 T9325 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2483634 T9325 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2483636 T9325 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13a4d3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ccf1b4),segFN=segments_1,generation=1}
[junit4] 2> 2483636 T9325 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483636 T9325 oass.SolrIndexSearcher.<init> Opening Searcher@34380594[collection1] main
[junit4] 2> 2483637 T9325 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2483637 T9325 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf/}
[junit4] 2> 2483637 T9325 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2483638 T9325 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2483638 T9325 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2483638 T9325 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2483638 T9325 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2483639 T9325 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2483639 T9325 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2483639 T9326 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34380594[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2483640 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2483641 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2483659 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2483668 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56936
[junit4] 2> 2483668 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2483668 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2483669 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave
[junit4] 2> 2483674 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/'
[junit4] 2> 2483731 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/solr.xml
[junit4] 2> 2483743 T9315 oasc.CoreContainer.<init> New CoreContainer 1344105969
[junit4] 2> 2483744 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/]
[junit4] 2> 2483745 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2483746 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2483746 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2483746 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2483747 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2483748 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2483748 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2483749 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2483750 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2483750 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2483751 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2483752 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2483757 T9337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/'
[junit4] 2> 2483796 T9337 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2483804 T9337 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2483805 T9337 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2483808 T9337 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2483811 T9337 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2483812 T9337 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/
[junit4] 2> 2483812 T9337 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2483813 T9337 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/
[junit4] 2> 2483813 T9337 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2483813 T9337 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2483814 T9337 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index/
[junit4] 2> 2483814 T9337 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2483814 T9337 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2483815 T9337 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2483816 T9337 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16613950 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f4418e3),segFN=segments_1,generation=1}
[junit4] 2> 2483817 T9337 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483818 T9337 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2483818 T9337 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2483818 T9337 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2483821 T9337 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2483822 T9337 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2483822 T9337 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2483822 T9337 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2483823 T9337 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16613950 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f4418e3),segFN=segments_1,generation=1}
[junit4] 2> 2483823 T9337 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483823 T9337 oass.SolrIndexSearcher.<init> Opening Searcher@6c997ab5[collection1] main
[junit4] 2> 2483824 T9337 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2483824 T9337 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf/}
[junit4] 2> 2483824 T9337 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2483825 T9337 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2483825 T9337 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2483825 T9337 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2483832 T9337 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2483832 T9337 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2483832 T9338 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c997ab5[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2483833 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2483833 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2483848 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1344105969
[junit4] 2> 2483850 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@35b45dfa
[junit4] 2> 2483851 T9315 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> 2483852 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2483852 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2483853 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2483853 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2483854 T9315 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2483854 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data;done=false>>]
[junit4] 2> 2483855 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2483856 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index;done=false>>]
[junit4] 2> 2483856 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2483857 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2483925 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2005654738
[junit4] 2> 2483927 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@42b37b98
[junit4] 2> 2483927 T9315 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> 2483927 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2483928 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2483928 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2483929 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2483929 T9315 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2483930 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index;done=false>>]
[junit4] 2> 2483930 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2483931 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data;done=false>>]
[junit4] 2> 2483931 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2483932 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2483984 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2483989 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56936
[junit4] 2> 2483990 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2483990 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2483991 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave
[junit4] 2> 2483991 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/'
[junit4] 2> 2484011 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/solr.xml
[junit4] 2> 2484019 T9315 oasc.CoreContainer.<init> New CoreContainer 1846283972
[junit4] 2> 2484019 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/]
[junit4] 2> 2484021 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484021 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484021 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484023 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484023 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484024 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484024 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484024 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484027 T9350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/'
[junit4] 2> 2484054 T9350 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484070 T9350 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484073 T9350 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484076 T9350 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484079 T9350 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484080 T9350 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/
[junit4] 2> 2484080 T9350 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2484080 T9350 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/
[junit4] 2> 2484081 T9350 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484081 T9350 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484082 T9350 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index/
[junit4] 2> 2484082 T9350 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484083 T9350 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484083 T9350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=24.92578125, floorSegmentMB=1.541015625, forceMergeDeletesPctAllowed=23.864403221045638, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3630192332435702
[junit4] 2> 2484086 T9350 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2484087 T9350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484088 T9350 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484088 T9350 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484090 T9350 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484091 T9350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484092 T9350 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484092 T9350 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484094 T9350 oass.SolrIndexSearcher.<init> Opening Searcher@44eb603d[collection1] main
[junit4] 2> 2484095 T9350 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484095 T9350 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf/}
[junit4] 2> 2484096 T9350 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484096 T9350 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484096 T9350 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484097 T9350 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484098 T9350 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2484098 T9350 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484098 T9351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44eb603d[collection1] main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2484099 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484099 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2484100 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484106 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56935
[junit4] 2> 2484108 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484108 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484109 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master
[junit4] 2> 2484110 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/'
[junit4] 2> 2484129 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/solr.xml
[junit4] 2> 2484136 T9315 oasc.CoreContainer.<init> New CoreContainer 116872752
[junit4] 2> 2484137 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/]
[junit4] 2> 2484138 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484138 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484139 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484139 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484139 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484140 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484140 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484140 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484141 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484141 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484142 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484142 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484145 T9363 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/'
[junit4] 2> 2484170 T9363 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484177 T9363 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484179 T9363 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484182 T9363 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484183 T9363 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484185 T9363 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/
[junit4] 2> 2484185 T9363 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2484186 T9363 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/
[junit4] 2> 2484187 T9363 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484187 T9363 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2484188 T9363 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index/
[junit4] 2> 2484188 T9363 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484188 T9363 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2484189 T9363 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=24.92578125, floorSegmentMB=1.541015625, forceMergeDeletesPctAllowed=23.864403221045638, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3630192332435702
[junit4] 2> 2484192 T9363 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2484193 T9363 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484194 T9363 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484196 T9363 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484196 T9363 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484197 T9363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484198 T9363 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484199 T9363 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484200 T9363 oass.SolrIndexSearcher.<init> Opening Searcher@7f6624fd[collection1] main
[junit4] 2> 2484201 T9363 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2484202 T9363 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf/}
[junit4] 2> 2484202 T9363 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2484203 T9363 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484203 T9363 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484203 T9363 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484204 T9363 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2484204 T9363 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2484204 T9363 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484204 T9364 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f6624fd[collection1] main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2484205 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484206 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1956 name=collection1 org.apache.solr.core.SolrCore@2285e29c
[junit4] 2> 2484211 T9344 C1956 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=24.92578125, floorSegmentMB=1.541015625, forceMergeDeletesPctAllowed=23.864403221045638, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3630192332435702
[junit4] 2> 2484213 T9344 C1956 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2484214 T9344 C1956 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484216 T9344 C1956 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123456]} 0 6
[junit4] 2> 2484219 T9345 C1956 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2484238 T9345 C1956 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_2,generation=2}
[junit4] 2> 2484238 T9345 C1956 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2484247 T9345 C1956 oass.SolrIndexSearcher.<init> Opening Searcher@5d7da349[collection1] main
[junit4] 2> 2484248 T9345 C1956 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2484248 T9351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d7da349[collection1] main{StandardDirectoryReader(segments_2:3 _0(4.9):C1)}
[junit4] 2> 2484249 T9345 C1956 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 30
[junit4] 2> 2484265 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1846283972
[junit4] 2> 2484266 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2285e29c
[junit4] 2> 2484267 T9315 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> 2484268 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2484268 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2484268 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2484270 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2484271 T9315 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2484271 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data;done=false>>]
[junit4] 2> 2484272 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484272 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index;done=false>>]
[junit4] 2> 2484272 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484273 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2484326 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484332 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56936
[junit4] 2> 2484336 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484336 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484337 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave
[junit4] 2> 2484337 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/'
[junit4] 2> 2484381 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/solr.xml
[junit4] 2> 2484395 T9315 oasc.CoreContainer.<init> New CoreContainer 632777705
[junit4] 2> 2484396 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/]
[junit4] 2> 2484396 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484396 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484398 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484398 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484399 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484399 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484400 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484401 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484405 T9375 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/'
[junit4] 2> 2484449 T9375 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484460 T9375 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484462 T9375 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484467 T9375 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484474 T9375 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484475 T9375 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/
[junit4] 2> 2484475 T9375 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2484476 T9375 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/
[junit4] 2> 2484476 T9375 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484477 T9375 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484477 T9375 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index/
[junit4] 2> 2484478 T9375 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484479 T9375 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484479 T9375 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484479 T9375 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484480 T9375 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484480 T9375 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484480 T9375 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484487 T9375 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484488 T9375 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484488 T9375 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484489 T9375 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484491 T9375 oass.SolrIndexSearcher.<init> Opening Searcher@168ccdc5[collection1] main
[junit4] 2> 2484491 T9375 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484491 T9375 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf/}
[junit4] 2> 2484492 T9375 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484492 T9375 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484492 T9375 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484493 T9375 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484494 T9375 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2484494 T9375 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484494 T9376 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@168ccdc5[collection1] main{StandardDirectoryReader(segments_2:3 _0(4.9):C1)}
[junit4] 2> 2484495 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484496 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2484504 T9379 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2484505 T9379 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> ASYNC NEW_CORE C1957 name=collection1 org.apache.solr.core.SolrCore@74c4684b
[junit4] 2> 2484508 T9358 C1957 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C1958 name=collection1 org.apache.solr.core.SolrCore@4dcff679
[junit4] 2> 2484509 T9370 C1958 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56935/solr/replication&command=fetchindex} status=0 QTime=7
[junit4] 2> 2484511 T9315 oas.SolrTestCaseJ4.tearDown ###Ending testNoWriter
[junit4] 2> 2484526 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=116872752
[junit4] 2> 2484528 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@74c4684b
[junit4] 2> 2484528 T9315 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> 2484528 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2484529 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2484529 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2484530 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2484531 T9315 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2484532 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index;done=false>>]
[junit4] 2> 2484532 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2484533 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data;done=false>>]
[junit4] 2> 2484533 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2484534 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2484601 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=632777705
[junit4] 2> 2484603 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4dcff679
[junit4] 2> 2484604 T9315 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> 2484605 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2484605 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2484605 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2484606 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2484607 T9315 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2484607 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index;done=false>>]
[junit4] 2> 2484608 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484608 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data;done=false>>]
[junit4] 2> 2484608 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484609 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2484695 T9315 oas.SolrTestCaseJ4.setUp ###Starting doTestBackup
[junit4] 2> 2484708 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484715 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56940
[junit4] 2> 2484716 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484717 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484717 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master
[junit4] 2> 2484718 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/'
[junit4] 2> 2484757 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/solr.xml
[junit4] 2> 2484781 T9315 oasc.CoreContainer.<init> New CoreContainer 841192868
[junit4] 2> 2484781 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/]
[junit4] 2> 2484782 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484782 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484784 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484784 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484784 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484785 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484785 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484786 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484789 T9390 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/'
[junit4] 2> 2484824 T9390 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484834 T9390 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484836 T9390 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484838 T9390 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484847 T9390 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484848 T9390 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/
[junit4] 2> 2484848 T9390 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2484849 T9390 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/
[junit4] 2> 2484849 T9390 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484850 T9390 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data
[junit4] 2> 2484850 T9390 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index/
[junit4] 2> 2484850 T9390 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484851 T9390 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index
[junit4] 2> 2484851 T9390 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2484857 T9390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cb4b1ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@274079ea),segFN=segments_1,generation=1}
[junit4] 2> 2484857 T9390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484858 T9390 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484858 T9390 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484858 T9390 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484863 T9390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484867 T9390 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484867 T9390 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484868 T9390 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2484868 T9390 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cb4b1ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@274079ea),segFN=segments_1,generation=1}
[junit4] 2> 2484869 T9390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484870 T9390 oass.SolrIndexSearcher.<init> Opening Searcher@5dba281f[collection1] main
[junit4] 2> 2484871 T9390 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/conf
[junit4] 2> 2484872 T9390 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/conf/}
[junit4] 2> 2484872 T9390 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/conf
[junit4] 2> 2484873 T9390 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484874 T9390 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484874 T9390 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484874 T9390 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2484875 T9390 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2484875 T9390 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484875 T9391 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5dba281f[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2484877 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484878 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2484894 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484903 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56941
[junit4] 2> 2484905 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484905 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484906 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave
[junit4] 2> 2484906 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/'
[junit4] 2> 2484929 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/solr.xml
[junit4] 2> 2484937 T9315 oasc.CoreContainer.<init> New CoreContainer 845368923
[junit4] 2> 2484937 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/]
[junit4] 2> 2484938 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484938 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484938 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484939 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484939 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484939 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484940 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484940 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484940 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484941 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484941 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484942 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484946 T9402 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/'
[junit4] 2> 2484971 T9402 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484979 T9402 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484980 T9402 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484983 T9402 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484989 T9402 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484990 T9402 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/
[junit4] 2> 2484990 T9402 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2484990 T9402 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/
[junit4] 2> 2484991 T9402 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484991 T9402 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data
[junit4] 2> 2484991 T9402 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/index/
[junit4] 2> 2484991 T9402 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484992 T9402 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/index
[junit4] 2> 2484992 T9402 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2484992 T9402 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7db94452 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e02b103),segFN=segments_1,generation=1}
[junit4] 2> 2484992 T9402 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484993 T9402 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484996 T9402 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484996 T9402 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484997 T9402 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484998 T9402 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484998 T9402 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484999 T9402 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2485001 T9402 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2485003 T9402 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2485003 T9402 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2485004 T9402 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2485005 T9402 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7db94452 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e02b103),segFN=segments_1,generation=1}
[junit4] 2> 2485005 T9402 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2485006 T9402 oass.SolrIndexSearcher.<init> Opening Searcher@644f36c[collection1] main
[junit4] 2> 2485006 T9402 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/conf
[junit4] 2> 2485007 T9402 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/conf/}
[junit4] 2> 2485007 T9402 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/conf
[junit4] 2> 2485008 T9402 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2485008 T9402 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2485008 T9402 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2485009 T9402 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2485009 T9402 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2485010 T9403 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@644f36c[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2485010 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2485011 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2485027 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=841192868
[junit4] 2> 2485028 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6b58966b
[junit4] 2> 2485028 T9315 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> 2485029 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2485029 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2485029 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2485030 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2485031 T9315 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2485032 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index;done=false>>]
[junit4] 2> 2485032 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index
[junit4] 2> 2485032 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data;done=false>>]
[junit4] 2> 2485033 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data
[...truncated too long message...]
]
[junit4] 2> 2528215 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451247/master/collection1/data
[junit4] 2> 2528215 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2528281 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1212786941
[junit4] 2> 2528282 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38797be8
[junit4] 2> 2528283 T9315 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> 2528285 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2528285 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2528285 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2528287 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2528287 T9315 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2528287 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data;done=false>>]
[junit4] 2> 2528288 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data
[junit4] 2> 2528289 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data/index;done=false>>]
[junit4] 2> 2528289 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data/index
[junit4] 2> 2528290 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2528376 T9315 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-4663FFA1657C3442-001
[junit4] 2> NOTE: test params are: codec=Lucene46: {name=PostingsFormat(name=FSTOrdPulsing41), id=PostingsFormat(name=FSTOrdPulsing41), newname=PostingsFormat(name=FSTOrdPulsing41)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ru, timezone=W-SU
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=64597440,total=416808960
[junit4] 2> NOTE: All tests run in this JVM: [MergeStrategyTest, ZkNodePropsTest, SolrCoreTest, TestSolrDeletionPolicy2, LegacyHTMLStripCharFilterTest, UpdateRequestProcessorFactoryTest, HdfsCollectionsAPIDistributedZkTest, SoftAutoCommitTest, BinaryUpdateRequestHandlerTest, TestCollationFieldDocValues, TermVectorComponentTest, PathHierarchyTokenizerFactoryTest, TestBinaryResponseWriter, RequestHandlersTest, RecoveryZkTest, MultiTermTest, DistributedSpellCheckComponentTest, TestSolrQueryParserDefaultOperatorResource, LeaderElectionIntegrationTest, TestLMDirichletSimilarityFactory, DistanceFunctionTest, TestSimpleQParserPlugin, DeleteReplicaTest, HighlighterConfigTest, TestExpandComponent, TestWordDelimiterFilterFactory, CollectionsAPIDistributedZkTest, TestManagedSchema, SimpleFacetsTest, TestBM25SimilarityFactory, TestHighlightDedupGrouping, TestDynamicFieldCollectionResource, ZkCLITest, CoreContainerCoreInitFailuresTest, DeleteInactiveReplicaTest, TestSolrJ, TestSolrXmlPersistence, CurrencyFieldOpenExchangeTest, TestAddFieldRealTimeGet, TestXIncludeConfig, TestArbitraryIndexDir, SearchHandlerTest, TestSchemaVersionResource, SolrIndexConfigTest, ResourceLoaderTest, TestJmxMonitoredMap, TestPostingsSolrHighlighter, TestFieldCollectionResource, ReturnFieldsTest, FieldMutatingUpdateProcessorTest, TestCoreContainer, OverseerStatusTest, DirectSolrSpellCheckerTest, OverseerRolesTest, IndexBasedSpellCheckerTest, TestCopyFieldCollectionResource, TestFieldTypeResource, TestRestManager, TestFreeTextSuggestions, HdfsRecoveryZkTest, MultiThreadedOCPTest, TestNonNRTOpen, DocumentAnalysisRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, NumericFieldsTest, OpenCloseCoreStressTest, DocValuesTest, ExternalFileFieldSortTest, TestReversedWildcardFilterFactory, FieldAnalysisRequestHandlerTest, TestNoOpRegenerator, CustomCollectionTest, DistributedQueryElevationComponentTest, TestHighFrequencyDictionaryFactory, TestRequestStatusCollectionAPI, TestSearcherReuse, TestComponentsName, ReplicationFactorTest, SignatureUpdateProcessorFactoryTest, FastVectorHighlighterTest, StatsComponentTest, NoCacheHeaderTest, TermsComponentTest, BlockCacheTest, TestStandardQParsers, BadCopyFieldTest, CircularListTest, HdfsBasicDistributedZk2Test, DateFieldTest, TestCSVLoader, DeleteShardTest, TestNRTOpen, TestCursorMarkWithoutUniqueKey, OverseerCollectionProcessorTest, CacheHeaderTest, PreAnalyzedFieldTest, DistributedExpandComponentTest, TestExtendedDismaxParser, TestInitQParser, DebugComponentTest, TestRTGBase, TestDefaultSimilarityFactory, TestMergePolicyConfig, SolrIndexSplitterTest, PrimitiveFieldTypeTest, QueryParsingTest, TestPartialUpdateDeduplication, TestOmitPositions, FileBasedSpellCheckerTest, TestPhraseSuggestions, TestSolrDeletionPolicy1, TestJoin, TestRandomFaceting, TestStressVersions, EchoParamsTest, TestLFUCache, DirectUpdateHandlerOptimizeTest, TestFieldSortValues, TestRandomDVFaceting, TestHashPartitioner, TestDocSet, TestStressLucene, ResponseHeaderTest, TestSearchPerf, PeerSyncTest, TestInfoStreamLogging, CoreMergeIndexesAdminHandlerTest, SortByFunctionTest, QueryResultKeyTest, RankQueryTest, DocumentBuilderTest, PrimUtilsTest, TestDFRSimilarityFactory, CoreAdminCreateDiscoverTest, TestRecovery, TestCloudManagedSchema, TestClassNameShortening, OverseerTest, ConnectionManagerTest, TestCharFilters, TestLuceneMatchVersion, TestCoreDiscovery, TestOverriddenPrefixQueryForCustomFieldType, SpellingQueryConverterTest, TestAnalyzeInfixSuggestions, DirectUpdateHandlerTest, TestFastWriter, HdfsDirectoryTest, TestAnalyzedSuggestions, LukeRequestHandlerTest, SolrXmlInZkTest, TestSystemIdResolver, SolrTestCaseJ4Test, QueryElevationComponentTest, CopyFieldTest, TestSchemaSimilarityResource, SolrRequestParserTest, TestFileDictionaryLookup, ClusterStateTest, TestExceedMaxTermLength, RAMDirectoryFactoryTest, TestSolrIndexConfig, TestRealTimeGet, ResponseLogComponentTest, BlockDirectoryTest, CollectionsAPIAsyncDistributedZkTest, SuggesterFSTTest, TestLRUCache, TestFastOutputStream, CurrencyFieldXmlFileTest, MigrateRouteKeyTest, TestMultiCoreConfBootstrap, SpellCheckComponentTest, TestSchemaResource, OutputWriterTest, SpatialFilterTest, HdfsUnloadDistributedZkTest, TestImplicitCoreProperties, DistributedQueryComponentCustomSortTest, RemoteQueryErrorTest, TestNumberUtils, SampleTest, TestConfigSets, TestMiniSolrCloudCluster, OpenExchangeRatesOrgProviderTest, HttpPartitionTest, ShardSplitTest, TestStressUserVersions, TestQueryTypes, DistributedSuggestComponentTest, NotRequiredUniqueKeyTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed in 44.93s, 14 tests, 1 error <<< FAILURES!
[...truncated 585 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1297: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:921: There were test failures: 396 suites, 1640 tests, 1 error, 41 ignored (14 assumptions)
Total time: 97 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure