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/11 15:09:40 UTC
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.8.0) - Build # 1533 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1533/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([FBAACF7C7023C77C:BA21EF19579D3433]:0)
at org.apache.solr.SolrTestCaseJ4.recurseDelete(SolrTestCaseJ4.java:1002)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1553)
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 11363 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-FBAACF7C7023C77C-001/init-core-data-001
[junit4] 2> 2367597 T8242 oas.SolrTestCaseJ4.setUp ###Starting doTestRepeater
[junit4] 2> 2367613 T8242 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2367621 T8242 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56032
[junit4] 2> 2367622 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2367624 T8242 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2367624 T8242 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master
[junit4] 2> 2367624 T8242 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/'
[junit4] 2> 2367661 T8242 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-1399812558993/master/solr.xml
[junit4] 2> 2367676 T8242 oasc.CoreContainer.<init> New CoreContainer 1580287343
[junit4] 2> 2367677 T8242 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/]
[junit4] 2> 2367677 T8242 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2367678 T8242 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2367678 T8242 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2367678 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2367679 T8242 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2367679 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2367680 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2367680 T8242 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2367680 T8242 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2367681 T8242 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2367682 T8242 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2367682 T8242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2367689 T8252 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/collection1/'
[junit4] 2> 2367720 T8252 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2367728 T8252 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2367729 T8252 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2367733 T8252 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2367740 T8252 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2367741 T8252 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/collection1/
[junit4] 2> 2367741 T8252 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2367742 T8252 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/collection1/data/
[junit4] 2> 2367742 T8252 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2367742 T8252 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-1399812558993/master/collection1/data
[junit4] 2> 2367742 T8252 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-1399812558993/master/collection1/data/index/
[junit4] 2> 2367743 T8252 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-1399812558993/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2367743 T8252 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-1399812558993/master/collection1/data/index
[junit4] 2> 2367744 T8252 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1749868015, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2367745 T8252 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@72abc092 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@376ab9e7),segFN=segments_1,generation=1}
[junit4] 2> 2367745 T8252 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2367747 T8252 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2367748 T8252 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2367749 T8252 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2367749 T8252 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2367749 T8252 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2367749 T8252 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2367750 T8252 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2367757 T8252 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2367758 T8252 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2367758 T8252 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2367759 T8252 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=263501244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2367759 T8252 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@72abc092 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@376ab9e7),segFN=segments_1,generation=1}
[junit4] 2> 2367760 T8252 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2367760 T8252 oass.SolrIndexSearcher.<init> Opening Searcher@7150b100[collection1] main
[junit4] 2> 2367760 T8252 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-1399812558993/master/collection1/conf
[junit4] 2> 2367761 T8252 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812558993/master/collection1/conf/}
[junit4] 2> 2367761 T8252 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-1399812558993/master/collection1/conf
[junit4] 2> 2367762 T8252 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2367762 T8252 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2367762 T8252 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2367763 T8252 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2367763 T8252 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2367763 T8252 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2367763 T8253 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7150b100[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2367764 T8242 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2367765 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2367784 T8242 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2367794 T8242 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56033
[junit4] 2> 2367795 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2367795 T8242 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2367795 T8242 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave
[junit4] 2> 2367796 T8242 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/'
[junit4] 2> 2367819 T8242 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-1399812559162/slave/solr.xml
[junit4] 2> 2367858 T8242 oasc.CoreContainer.<init> New CoreContainer 1891403314
[junit4] 2> 2367859 T8242 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/]
[junit4] 2> 2367859 T8242 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2367860 T8242 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2367860 T8242 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2367861 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2367861 T8242 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2367861 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2367862 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2367862 T8242 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2367863 T8242 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2367863 T8242 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2367864 T8242 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2367865 T8242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2367868 T8264 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/'
[junit4] 2> 2367897 T8264 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2367907 T8264 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2367909 T8264 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2367912 T8264 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2367914 T8264 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2367916 T8264 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/
[junit4] 2> 2367917 T8264 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2367918 T8264 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/data/
[junit4] 2> 2367919 T8264 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2367920 T8264 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-1399812559162/slave/collection1/data
[junit4] 2> 2367921 T8264 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-1399812559162/slave/collection1/data/index/
[junit4] 2> 2367921 T8264 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-1399812559162/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2367923 T8264 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-1399812559162/slave/collection1/data/index
[junit4] 2> 2367926 T8264 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1749868015, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2367926 T8264 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5fd3fde5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b909d9),segFN=segments_1,generation=1}
[junit4] 2> 2367927 T8264 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2367928 T8264 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2367928 T8264 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2367929 T8264 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2367929 T8264 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2367930 T8264 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2367930 T8264 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2367931 T8264 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2367936 T8264 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2367938 T8264 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2367938 T8264 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2367940 T8264 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=263501244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2367940 T8264 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5fd3fde5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b909d9),segFN=segments_1,generation=1}
[junit4] 2> 2367940 T8264 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2367941 T8264 oass.SolrIndexSearcher.<init> Opening Searcher@7a75d9d1[collection1] main
[junit4] 2> 2367941 T8264 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-1399812559162/slave/collection1/conf
[junit4] 2> 2367941 T8264 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/conf/}
[junit4] 2> 2367942 T8264 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-1399812559162/slave/collection1/conf
[junit4] 2> 2367943 T8264 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2367943 T8264 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2367944 T8264 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2367952 T8264 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2367952 T8264 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2367952 T8265 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a75d9d1[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2367954 T8242 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2367954 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2367970 T8242 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1891403314
[junit4] 2> 2367972 T8242 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1bd34578
[junit4] 2> 2367973 T8242 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> 2367973 T8242 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2367973 T8242 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2367974 T8242 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2367974 T8242 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2367975 T8242 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2367975 T8242 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-1399812559162/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-1399812559162/slave/collection1/data/index;done=false>>]
[junit4] 2> 2367975 T8242 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-1399812559162/slave/collection1/data/index
[junit4] 2> 2367976 T8242 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-1399812559162/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-1399812559162/slave/collection1/data;done=false>>]
[junit4] 2> 2367976 T8242 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-1399812559162/slave/collection1/data
[junit4] 2> 2367977 T8242 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2368035 T8242 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2368042 T8242 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56034
[junit4] 2> 2368042 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2368043 T8242 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2368043 T8242 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave
[junit4] 2> 2368044 T8242 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/'
[junit4] 2> 2368072 T8242 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-1399812559162/slave/solr.xml
[junit4] 2> 2368082 T8242 oasc.CoreContainer.<init> New CoreContainer 651764109
[junit4] 2> 2368082 T8242 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/]
[junit4] 2> 2368082 T8242 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2368083 T8242 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2368083 T8242 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2368084 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2368084 T8242 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2368084 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2368085 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2368085 T8242 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2368085 T8242 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2368086 T8242 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2368086 T8242 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2368087 T8242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2368089 T8277 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/'
[junit4] 2> 2368120 T8277 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2368127 T8277 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2368129 T8277 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2368132 T8277 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2368135 T8277 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2368135 T8277 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/
[junit4] 2> 2368135 T8277 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2368136 T8277 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/data/
[junit4] 2> 2368136 T8277 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2368136 T8277 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-1399812559162/slave/collection1/data
[junit4] 2> 2368137 T8277 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-1399812559162/slave/collection1/data/index/
[junit4] 2> 2368137 T8277 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-1399812559162/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2368138 T8277 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-1399812559162/slave/collection1/data/index
[junit4] 2> 2368139 T8277 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1749868015, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2368140 T8277 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@386398b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9f8f673),segFN=segments_1,generation=1}
[junit4] 2> 2368140 T8277 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2368141 T8277 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2368141 T8277 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2368141 T8277 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2368141 T8277 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2368141 T8277 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2368142 T8277 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2368142 T8277 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2368143 T8277 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2368144 T8277 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2368145 T8277 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2368145 T8277 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=263501244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2368146 T8277 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@386398b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9f8f673),segFN=segments_1,generation=1}
[junit4] 2> 2368146 T8277 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2368146 T8277 oass.SolrIndexSearcher.<init> Opening Searcher@2e81076b[collection1] main
[junit4] 2> 2368147 T8277 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-1399812559162/slave/collection1/conf
[junit4] 2> 2368147 T8277 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/conf/}
[junit4] 2> 2368147 T8277 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-1399812559162/slave/collection1/conf
[junit4] 2> 2368148 T8277 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2368149 T8277 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2368149 T8277 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2368150 T8277 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2368150 T8277 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2368150 T8278 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e81076b[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2368151 T8242 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2368152 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2368164 T8242 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2368170 T8242 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56035
[junit4] 2> 2368171 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2368172 T8242 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2368172 T8242 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater
[junit4] 2> 2368172 T8242 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/'
[junit4] 2> 2368192 T8242 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-1399812559547/repeater/solr.xml
[junit4] 2> 2368201 T8242 oasc.CoreContainer.<init> New CoreContainer 518453469
[junit4] 2> 2368202 T8242 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/]
[junit4] 2> 2368202 T8242 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2368203 T8242 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2368203 T8242 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2368203 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2368203 T8242 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2368204 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2368204 T8242 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2368204 T8242 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2368204 T8242 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2368205 T8242 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2368205 T8242 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2368205 T8242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2368209 T8289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/'
[junit4] 2> 2368238 T8289 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2368247 T8289 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2368248 T8289 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2368251 T8289 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2368254 T8289 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2368254 T8289 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/
[junit4] 2> 2368255 T8289 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2368255 T8289 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/data/
[junit4] 2> 2368255 T8289 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2368256 T8289 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-1399812559547/repeater/collection1/data
[junit4] 2> 2368257 T8289 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-1399812559547/repeater/collection1/data/index/
[junit4] 2> 2368257 T8289 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-1399812559547/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2368258 T8289 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-1399812559547/repeater/collection1/data/index
[junit4] 2> 2368258 T8289 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1749868015, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2368259 T8289 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922),segFN=segments_1,generation=1}
[junit4] 2> 2368259 T8289 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2368261 T8289 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2368261 T8289 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2368261 T8289 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2368261 T8289 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2368262 T8289 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2368262 T8289 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2368262 T8289 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2368264 T8289 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2368265 T8289 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2368265 T8289 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2368266 T8289 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=263501244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2368266 T8289 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922),segFN=segments_1,generation=1}
[junit4] 2> 2368266 T8289 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2368267 T8289 oass.SolrIndexSearcher.<init> Opening Searcher@7c2eeec[collection1] main
[junit4] 2> 2368267 T8289 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-1399812559547/repeater/collection1/conf
[junit4] 2> 2368267 T8289 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/conf/}
[junit4] 2> 2368268 T8289 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-1399812559547/repeater/collection1/conf
[junit4] 2> 2368268 T8289 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2368268 T8289 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2368269 T8289 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2368269 T8289 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2368269 T8289 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2368269 T8289 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 2368269 T8289 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2368270 T8289 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2368270 T8290 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7c2eeec[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2368272 T8242 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2368272 T8242 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C2018 name=collection1 org.apache.solr.core.SolrCore@4dabc28b
[junit4] 2> 2368275 T8247 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 2368278 T8248 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2368281 T8249 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2368285 T8250 C2018 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2368287 T8250 C2018 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@72abc092 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@376ab9e7),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@72abc092 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@376ab9e7),segFN=segments_2,generation=2}
[junit4] 2> 2368288 T8250 C2018 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2368289 T8250 C2018 oass.SolrIndexSearcher.<init> Opening Searcher@212d4f82[collection1] main
[junit4] 2> 2368289 T8250 C2018 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2368289 T8253 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@212d4f82[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):c3)}
[junit4] 2> 2368290 T8250 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 5
[junit4] 2> 2368319 T8292 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2368321 T8292 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2368323 T8251 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368324 T8292 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2368324 T8292 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2368324 T8292 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2368334 T8246 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=8
[junit4] 2> 2368335 T8292 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 2368336 T8292 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-1399812559547/repeater/collection1/data/index.20140511094919730
[junit4] 2> 2368336 T8292 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2368336 T8292 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2368336 T8292 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2368336 T8292 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@3084984b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44b984a6) fullCopy=false
[junit4] 2> 2368339 T8247 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368343 T8248 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368346 T8249 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368350 T8250 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368351 T8292 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2368355 T8292 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2368356 T8292 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=20.6572265625, floorSegmentMB=1.0390625, forceMergeDeletesPctAllowed=16.89731428359589, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2368356 T8292 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922),segFN=segments_2,generation=2}
[junit4] 2> 2368358 T8292 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2368358 T8292 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2368359 T8292 oass.SolrIndexSearcher.<init> Opening Searcher@4410725[collection1] main
[junit4] 2> 2368359 T8290 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4410725[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):c3)}
[junit4] 2> 2368359 T8292 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-1399812559547/repeater/collection1/data/index.20140511094919730 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/data/index.20140511094919730;done=true>>]
[junit4] 2> 2368360 T8292 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-1399812559547/repeater/collection1/data/index.20140511094919730
[junit4] 2> ASYNC NEW_CORE C2019 name=collection1 org.apache.solr.core.SolrCore@1ecc88f
[junit4] 2> 2368360 T8283 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56032/solr/replication&command=fetchindex} status=0 QTime=67
[junit4] 2> 2368367 T8285 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=1
[junit4] 2> 2368373 T8295 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2368373 T8295 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2368376 T8286 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368377 T8295 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2368378 T8295 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2368378 T8295 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2368382 T8287 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 2368382 T8295 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 2368383 T8295 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-1399812559162/slave/collection1/data/index.20140511094919778
[junit4] 2> 2368383 T8295 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2368384 T8295 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2368384 T8295 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2368384 T8295 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@182cef65 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6cb9ee4a) fullCopy=false
[junit4] 2> 2368386 T8288 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368390 T8283 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368393 T8284 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368396 T8285 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368397 T8295 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2368399 T8295 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2368400 T8295 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=20.6572265625, floorSegmentMB=1.0390625, forceMergeDeletesPctAllowed=16.89731428359589, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2368400 T8295 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@386398b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9f8f673),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@386398b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9f8f673),segFN=segments_2,generation=2}
[junit4] 2> 2368402 T8295 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2368402 T8295 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2368403 T8295 oass.SolrIndexSearcher.<init> Opening Searcher@741dac6a[collection1] main
[junit4] 2> 2368404 T8278 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@741dac6a[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):c3)}
[junit4] 2> 2368404 T8295 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-1399812559162/slave/collection1/data/index.20140511094919778 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559162/slave/collection1/data/index.20140511094919778;done=true>>]
[junit4] 2> 2368404 T8295 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-1399812559162/slave/collection1/data/index.20140511094919778
[junit4] 2> ASYNC NEW_CORE C2020 name=collection1 org.apache.solr.core.SolrCore@415fd469
[junit4] 2> 2368405 T8271 C2020 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56035/solr/replication&command=fetchindex} status=0 QTime=33
[junit4] 2> 2368411 T8273 C2020 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 2368414 T8251 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 2368417 T8246 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 2368423 T8286 C2019 oash.ReplicationHandler.getReplicationDetails WARN Exception while invoking 'details' method for replication on master org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:560)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] 2> at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1657)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:658)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:263)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1962)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:769)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:134)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:345)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:589)
[junit4] 2> at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] 2> at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:178)
[junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:610)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:445)
[junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:452)
[junit4] 2> ... 35 more
[junit4] 2>
[junit4] 2> 2368428 T8286 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=7
[junit4] 2> 2368432 T8247 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368435 T8287 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368440 T8288 C2019 oash.ReplicationHandler.getReplicationDetails WARN Exception while invoking 'details' method for replication on master org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:560)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] 2> at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1657)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:658)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:263)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1962)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:769)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:134)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:345)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:589)
[junit4] 2> at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] 2> at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:178)
[junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:610)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:445)
[junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:452)
[junit4] 2> ... 35 more
[junit4] 2>
[junit4] 2> 2368443 T8288 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=5
[junit4] 2> 2368449 T8283 C2019 oash.ReplicationHandler.getReplicationDetails WARN Exception while invoking 'details' method for replication on master org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:560)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] 2> at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1657)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:658)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:263)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1962)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:769)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:134)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:345)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:589)
[junit4] 2> at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] 2> at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:178)
[junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:610)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:445)
[junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:452)
[junit4] 2> ... 35 more
[junit4] 2>
[junit4] 2> 2368451 T8283 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
[junit4] 2> 2368459 T8284 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 2368460 T8274 C2020 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=6
[junit4] 2> 2368464 T8285 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368467 T8270 C2020 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=1
[junit4] 2> 2368470 T8286 C2019 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 2368474 T8287 C2019 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2368477 T8288 C2019 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2368479 T8283 C2019 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 2368482 T8284 C2019 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2368486 T8284 C2019 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922),segFN=segments_3,generation=3}
[junit4] 2> 2368486 T8284 C2019 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2368487 T8284 C2019 oass.SolrIndexSearcher.<init> Opening Searcher@1dae5abd[collection1] main
[junit4] 2> 2368488 T8284 C2019 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2368488 T8290 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dae5abd[collection1] main{StandardDirectoryReader(segments_3:7:nrt _1(4.9):c4)}
[junit4] 2> 2368490 T8284 C2019 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 8
[junit4] 2> 2368493 T8297 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2368493 T8297 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2368497 T8249 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368498 T8297 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2368498 T8297 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 2368498 T8297 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2368503 T8250 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 2368504 T8297 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 2368505 T8297 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-1399812559547/repeater/collection1/data/index.20140511094919900
[junit4] 2> 2368507 T8297 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@56f73505 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@82e857) fullCopy=true
[junit4] 2> 2368510 T8251 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368515 T8246 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368518 T8248 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368522 T8247 C2018 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2368524 T8297 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2368525 T8297 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20140511094919900
[junit4] 2> 2368526 T8297 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirectoryWrapper(RAMDirectory@39228d47 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7328d922)
[junit4] 2> 2368527 T8297 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 2368528 T8297 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2368528 T8297 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2368529 T8297 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-1399812559547/repeater/collection1/data/index/ new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812559547/repeater/collection1/data/index.20140511094919900
[junit4] 2> 2368530 T8297 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=20.6572265625, floorSegmentMB=1.0390625, forceMergeDeletesPctAllowed=16.89731428359589, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2368531 T8297 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@56f73505 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@82e857),segFN=segments_2,generation=2}
[junit4] 2> 2368531 T8297 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2368532 T8297 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2368533 T8297 oass.SolrIndexSearcher.<init> Opening Searcher@2ad5bf4d[collection1] main
[junit4] 2> 2368533 T8290 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ad5bf4d[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):c3)}
[junit4] 2> 2368534 T8285 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56032/solr/replication&command=fetchindex} status=0 QTime=42
[junit4] 2> 2368535 T8285 C2019 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-1399812559547/repeater/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-1399812559547/repeater/collection1/data/index;done=true>>]
[junit4] 2> 2368535 T8285 C2019 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-1399812559547/repeater/collection1/data/index
[junit4] 2> 2368538 T8286 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 2368541 T8299 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2368541 T8299 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2368544 T8287 C2019 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2368545 T8299 oash.SnapPuller.fetchLatestIndex Slave in sync with master.
[junit4] 2> 2368545 T8271 C2020 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56035/solr/replication&command=fetchindex} status=0 QTime=5
[junit4] 2> 2368550 T8272 C2020 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 2368555 T8249 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4] 2> 2368559 T8250 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 2368563 T8251 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 2368566 T8246 C2018 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2368572 T8246 C2018 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@72abc092 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@376ab9e7),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@72abc092 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@376ab9e7),segFN=segments_3,generation=3}
[junit4] 2> 2368573 T8246 C2018 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2368574 T8246 C2018 oass.SolrIndexSearcher.<init> Opening Searcher@39833fed[collection1] main
[junit4] 2> 2368575 T8246 C2018 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2368575 T8253 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@39833fed[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.9):c3 _1(4.9):c3)}
[junit4] 2> 2368576 T8246 C2018 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 10
[junit4] 2> 2368581 T8300 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2368581 T8300 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2368584 T8248 C2018 oasc.SolrCore.execute [collection1]
[...truncated too long message...]
7;done=false>>]
[junit4] 2> 2412711 T8242 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-1399812601437/master/collection1/data/index.20140511095003827
[junit4] 2> 2412712 T8242 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2412777 T8242 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2081243951
[junit4] 2> 2412778 T8242 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@43e7f1d6
[junit4] 2> 2412779 T8242 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 2412780 T8242 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2412781 T8242 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2412781 T8242 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2412782 T8242 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2412783 T8242 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2412783 T8242 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-1399812601643/slave/collection1/data/index.20140511095003563 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1399812601643/slave/collection1/data/index.20140511095003563;done=false>>]
[junit4] 2> 2412783 T8242 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-1399812601643/slave/collection1/data/index.20140511095003563
[junit4] 2> 2412784 T8242 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-1399812601643/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-1399812601643/slave/collection1/data;done=false>>]
[junit4] 2> 2412784 T8242 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-1399812601643/slave/collection1/data
[junit4] 2> 2412785 T8242 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2412858 T8242 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-FBAACF7C7023C77C-001
[junit4] 2> NOTE: test params are: codec=Lucene46: {name=Pulsing41(freqCutoff=7 minBlockSize=52 maxBlockSize=187), id=Pulsing41(freqCutoff=7 minBlockSize=52 maxBlockSize=187), newname=Pulsing41(freqCutoff=7 minBlockSize=52 maxBlockSize=187)}, docValues:{}, sim=DefaultSimilarity, locale=es_BO, timezone=America/Montevideo
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=412014920,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrDeletionPolicy2, TestFunctionQuery, SpatialFilterTest, TestQueryTypes, DocumentBuilderTest, TestCollapseQParserPlugin, BJQParserTest, TestReloadAndDeleteDocs, BasicFunctionalityTest, SuggestComponentTest, HdfsCollectionsAPIDistributedZkTest, TestAnalyzeInfixSuggestions, TestSolrXMLSerializer, ShardSplitTest, TestExpandComponent, TestCollationField, TestLFUCache, DistanceFunctionTest, TestLRUCache, TestComponentsName, AlternateDirectoryTest, QueryElevationComponentTest, XsltUpdateRequestHandlerTest, TestFastWriter, TestReload, MinimalSchemaTest, TestCollectionAPI, FileBasedSpellCheckerTest, TestSimpleQParserPlugin, SchemaVersionSpecificBehaviorTest, MultiTermTest, IndexSchemaRuntimeFieldTest, CoreAdminHandlerTest, URLClassifyProcessorTest, SystemInfoHandlerTest, ResponseLogComponentTest, ClusterStateUpdateTest, QueryResultKeyTest, StressHdfsTest, TestConfigSets, EnumFieldTest, StatsComponentTest, CursorPagingTest, TestBinaryField, RAMDirectoryFactoryTest, TestJoin, TestDFRSimilarityFactory, DirectSolrConnectionTest, TestReversedWildcardFilterFactory, TestQuerySenderListener, CurrencyFieldOpenExchangeTest, SolrRequestParserTest, TestLMJelinekMercerSimilarityFactory, TestShardHandlerFactory, TestOmitPositions, DocumentAnalysisRequestHandlerTest, TestAtomicUpdateErrorCases, TestFileDictionaryLookup, LukeRequestHandlerTest, StandardRequestHandlerTest, MBeansHandlerTest, EchoParamsTest, TestBlendedInfixSuggestions, ZkControllerTest, FastVectorHighlighterTest, TestElisionMultitermQuery, TestRequestStatusCollectionAPI, TestCustomSort, TestInitQParser, TestClassNameShortening, TestUniqueKeyFieldResource, AnalysisAfterCoreReloadTest, TestCharFilters, TestRTGBase, PathHierarchyTokenizerFactoryTest, TestCollationKeyRangeQueries, TestManagedStopFilterFactory, ZkSolrClientTest, CustomCollectionTest, HdfsSyncSliceTest, TestPhraseSuggestions, SuggesterTSTTest, TestDistributedMissingSort, DirectUpdateHandlerTest, OpenCloseCoreStressTest, DateFieldTest, QueryEqualityTest, TestStressReorder, TestPseudoReturnFields, OverseerRolesTest, TestComplexPhraseQParserPlugin, SoftAutoCommitTest, DistributedQueryComponentCustomSortTest, ScriptEngineTest, TestManagedSynonymFilterFactory, TestRangeQuery, SliceStateTest, CollectionsAPIAsyncDistributedZkTest, TestSort, TestSweetSpotSimilarityFactory, TestFoldingMultitermQuery, TestCursorMarkWithoutUniqueKey, InfoHandlerTest, TestCopyFieldCollectionResource, BinaryUpdateRequestHandlerTest, FieldAnalysisRequestHandlerTest, TestPerFieldSimilarity, CacheHeaderTest, SolrPluginUtilsTest, TestJmxMonitoredMap, TestFieldResource, SuggesterFSTTest, TestSchemaVersionResource, ResourceLoaderTest, TestNoOpRegenerator, DirectSolrSpellCheckerTest, DOMUtilTest, TestSolrQueryParserDefaultOperatorResource, TestOverriddenPrefixQueryForCustomFieldType, HdfsLockFactoryTest, ChangedSchemaMergeTest, TestDefaultSimilarityFactory, TestExtendedDismaxParser, UnloadDistributedZkTest, SyncSliceTest, MoreLikeThisHandlerTest, TestImplicitCoreProperties, PluginInfoTest, TimeZoneUtilsTest, HdfsDirectoryTest, MigrateRouteKeyTest, TestDocSet, TestDistribDocBasedVersion, ParsingFieldUpdateProcessorsTest, TestLazyCores, CollectionsAPIDistributedZkTest, TestSchemaSimilarityResource, TestNRTOpen, TestHashPartitioner, ShowFileRequestHandlerTest, TestHighlightDedupGrouping, TestStressUserVersions, TestCodecSupport, TestDynamicFieldResource, FileUtilsTest, RemoteQueryErrorTest, CoreMergeIndexesAdminHandlerTest, DeleteReplicaTest, TestDistributedGrouping, TestStressRecovery, LeaderElectionIntegrationTest, CoreContainerCoreInitFailuresTest, CachingDirectoryFactoryTest, TestBM25SimilarityFactory, DistribCursorPagingTest, TestFaceting, SynonymTokenizerTest, AddSchemaFieldsUpdateProcessorFactoryTest, TriLevelCompositeIdRoutingTest, ExternalFileFieldSortTest, SearchHandlerTest, TestLuceneMatchVersion, TestSchemaResource, TestDocumentBuilder, TestSolrXml, TestSolrDeletionPolicy1, SolrXmlInZkTest, UpdateRequestProcessorFactoryTest, TestManagedSchemaFieldResource, NoCacheHeaderTest, TestCloudInspectUtil, TestUtils, TestValueSourceCache, TestSerializedLuceneMatchVersion, SolrIndexConfigTest, DefaultValueUpdateProcessorTest, NumericFieldsTest, ZkCLITest, TestUpdate, DistributedQueryComponentOptimizationTest, AnalysisErrorHandlingTest, TestQuerySenderNoQuery, TestSuggestSpellingConverter, AutoCommitTest, TestRestManager, TestCollationFieldDocValues, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OverseerTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed in 45.30s, 14 tests, 1 error <<< FAILURES!
[...truncated 580 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: 390 suites, 1635 tests, 1 error, 42 ignored (15 assumptions)
Total time: 94 minutes 32 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure