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/30 10:29:47 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1605
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1605/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([C3F97C04E5DE72DD:82725C61C2608192]:0)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:123)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:126)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:110)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1556)
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.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 11228 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/init-core-data-001
[junit4] 2> 2321963 T8920 oas.SolrTestCaseJ4.setUp ###Starting doTestRepeater
[junit4] 2> 2322001 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2322006 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52306
[junit4] 2> 2322007 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2322008 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2322008 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001
[junit4] 2> 2322009 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/'
[junit4] 2> 2322054 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/solr.xml
[junit4] 2> 2322068 T8920 oasc.CoreContainer.<init> New CoreContainer 1371501479
[junit4] 2> 2322068 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/]
[junit4] 2> 2322069 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2322069 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2322069 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2322070 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2322070 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2322070 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2322071 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2322071 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2322071 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2322073 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2322073 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2322073 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2322081 T8930 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/'
[junit4] 2> 2322104 T8930 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 2322112 T8930 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2322113 T8930 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2322115 T8930 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2322120 T8930 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2322121 T8930 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/
[junit4] 2> 2322122 T8930 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2322123 T8930 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/
[junit4] 2> 2322123 T8930 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2322123 T8930 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data
[junit4] 2> 2322124 T8930 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index/
[junit4] 2> 2322124 T8930 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2322125 T8930 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index
[junit4] 2> 2322125 T8930 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2322125 T8930 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_1,generation=1}
[junit4] 2> 2322126 T8930 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322127 T8930 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2322129 T8930 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2322129 T8930 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2322137 T8930 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2322138 T8930 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2322138 T8930 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2322139 T8930 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
[junit4] 2> 2322139 T8930 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_1,generation=1}
[junit4] 2> 2322139 T8930 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322139 T8930 oass.SolrIndexSearcher.<init> Opening Searcher@147a8b2a[collection1] main
[junit4] 2> 2322140 T8930 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/conf
[junit4] 2> 2322140 T8930 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/conf/}
[junit4] 2> 2322140 T8930 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/conf
[junit4] 2> 2322141 T8930 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2322141 T8930 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2322141 T8930 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2322141 T8930 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2322142 T8930 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2322142 T8930 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2322142 T8931 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@147a8b2a[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 2322144 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2322144 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2322155 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2322159 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52307
[junit4] 2> 2322161 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2322162 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2322162 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002
[junit4] 2> 2322162 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/'
[junit4] 2> 2322183 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/solr.xml
[junit4] 2> 2322191 T8920 oasc.CoreContainer.<init> New CoreContainer 6824207
[junit4] 2> 2322191 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/]
[junit4] 2> 2322192 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2322192 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2322192 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2322193 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2322193 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2322193 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2322194 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2322194 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2322194 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2322195 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2322195 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2322195 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2322198 T8942 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/'
[junit4] 2> 2322233 T8942 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 2322238 T8942 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2322240 T8942 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2322243 T8942 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2322246 T8942 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2322246 T8942 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/
[junit4] 2> 2322246 T8942 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2322247 T8942 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/
[junit4] 2> 2322247 T8942 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2322247 T8942 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
[junit4] 2> 2322247 T8942 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index/
[junit4] 2> 2322248 T8942 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2322249 T8942 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
[junit4] 2> 2322250 T8942 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2322251 T8942 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7428385f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3aba1deb),segFN=segments_1,generation=1}
[junit4] 2> 2322251 T8942 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322253 T8942 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2322253 T8942 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2322256 T8942 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2322257 T8942 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2322259 T8942 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2322259 T8942 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2322259 T8942 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
[junit4] 2> 2322260 T8942 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7428385f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3aba1deb),segFN=segments_1,generation=1}
[junit4] 2> 2322260 T8942 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322260 T8942 oass.SolrIndexSearcher.<init> Opening Searcher@658125e7[collection1] main
[junit4] 2> 2322260 T8942 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
[junit4] 2> 2322261 T8942 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf/}
[junit4] 2> 2322261 T8942 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
[junit4] 2> 2322262 T8942 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2322262 T8942 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2322262 T8942 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2322271 T8942 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2322271 T8942 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2322271 T8943 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@658125e7[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 2322272 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2322273 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2322290 T8920 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6824207
[junit4] 2> 2322292 T8920 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@777e6ff
[junit4] 2> 2322292 T8920 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> 2322293 T8920 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2322293 T8920 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2322294 T8920 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2322294 T8920 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2322295 T8920 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2322295 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data;done=false>>]
[junit4] 2> 2322295 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
[junit4] 2> 2322296 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index;done=false>>]
[junit4] 2> 2322296 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
[junit4] 2> 2322297 T8920 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2322351 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2322357 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52308
[junit4] 2> 2322359 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2322359 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2322359 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002
[junit4] 2> 2322359 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/'
[junit4] 2> 2322379 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/solr.xml
[junit4] 2> 2322385 T8920 oasc.CoreContainer.<init> New CoreContainer 1536040162
[junit4] 2> 2322386 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/]
[junit4] 2> 2322386 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2322387 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2322387 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2322388 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2322388 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2322390 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2322391 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2322391 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2322395 T8955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/'
[junit4] 2> 2322422 T8955 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 2322428 T8955 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2322428 T8955 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2322432 T8955 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2322434 T8955 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2322434 T8955 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/
[junit4] 2> 2322435 T8955 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2322435 T8955 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/
[junit4] 2> 2322435 T8955 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2322436 T8955 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
[junit4] 2> 2322436 T8955 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index/
[junit4] 2> 2322436 T8955 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2322437 T8955 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
[junit4] 2> 2322438 T8955 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2322439 T8955 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_1,generation=1}
[junit4] 2> 2322439 T8955 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322440 T8955 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2322440 T8955 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2322441 T8955 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2322441 T8955 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2322441 T8955 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2322442 T8955 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2322442 T8955 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2322443 T8955 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2322444 T8955 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2322445 T8955 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2322445 T8955 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
[junit4] 2> 2322445 T8955 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_1,generation=1}
[junit4] 2> 2322445 T8955 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322446 T8955 oass.SolrIndexSearcher.<init> Opening Searcher@24be5a09[collection1] main
[junit4] 2> 2322446 T8955 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
[junit4] 2> 2322446 T8955 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf/}
[junit4] 2> 2322447 T8955 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
[junit4] 2> 2322447 T8955 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2322447 T8955 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2322447 T8955 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2322448 T8955 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2322448 T8955 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2322448 T8956 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24be5a09[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 2322449 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2322450 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2322460 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2322467 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52309
[junit4] 2> 2322469 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2322469 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2322469 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003
[junit4] 2> 2322469 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/'
[junit4] 2> 2322498 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/solr.xml
[junit4] 2> 2322507 T8920 oasc.CoreContainer.<init> New CoreContainer 587829046
[junit4] 2> 2322508 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/]
[junit4] 2> 2322508 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2322509 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2322509 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2322510 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2322510 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2322510 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2322511 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2322511 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2322511 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2322512 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2322513 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2322513 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2322516 T8967 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/'
[junit4] 2> 2322544 T8967 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 2322551 T8967 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2322553 T8967 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2322555 T8967 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2322558 T8967 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2322558 T8967 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/
[junit4] 2> 2322559 T8967 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2322559 T8967 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/
[junit4] 2> 2322559 T8967 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2322560 T8967 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data
[junit4] 2> 2322560 T8967 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index/
[junit4] 2> 2322561 T8967 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2322562 T8967 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index
[junit4] 2> 2322562 T8967 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2322563 T8967 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_1,generation=1}
[junit4] 2> 2322563 T8967 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322564 T8967 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2322564 T8967 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2322568 T8967 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2322569 T8967 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2322569 T8967 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2322569 T8967 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
[junit4] 2> 2322570 T8967 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_1,generation=1}
[junit4] 2> 2322570 T8967 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2322570 T8967 oass.SolrIndexSearcher.<init> Opening Searcher@303be6b2[collection1] main
[junit4] 2> 2322570 T8967 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/conf
[junit4] 2> 2322571 T8967 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/conf/}
[junit4] 2> 2322571 T8967 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/conf
[junit4] 2> 2322572 T8967 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2322572 T8967 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2322572 T8967 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2322573 T8967 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2322573 T8967 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2322574 T8967 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 2322574 T8967 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2322574 T8967 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2322574 T8968 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@303be6b2[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 2322575 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2322575 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C2886 name=collection1 org.apache.solr.core.SolrCore@41c26dd6
[junit4] 2> 2322579 T8925 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 2322582 T8926 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2322585 T8927 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2322588 T8928 C2886 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2322590 T8928 C2886 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_2,generation=2}
[junit4] 2> 2322591 T8928 C2886 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2322592 T8928 C2886 oass.SolrIndexSearcher.<init> Opening Searcher@21c200ec[collection1] main
[junit4] 2> 2322593 T8928 C2886 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2322593 T8931 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21c200ec[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C3))}
[junit4] 2> 2322594 T8928 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 6
[junit4] 2> 2322598 T8970 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2322599 T8970 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2322601 T8929 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2322603 T8970 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2322603 T8970 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2322603 T8970 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2322612 T8924 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=7
[junit4] 2> 2322613 T8970 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 2322613 T8970 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095
[junit4] 2> 2322613 T8970 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2322613 T8970 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2322614 T8970 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2322614 T8970 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@1a4d48a9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6eda2a0c) fullCopy=false
[junit4] 2> 2322616 T8925 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322621 T8926 C2886 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> 2322625 T8927 C2886 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> 2322629 T8928 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=1
[junit4] 2> 2322632 T8929 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322635 T8924 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322639 T8925 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322642 T8926 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322645 T8927 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322648 T8928 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322649 T8970 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2322651 T8970 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2322651 T8970 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2322653 T8970 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_2,generation=2}
[junit4] 2> 2322654 T8970 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2322654 T8970 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2322655 T8970 oass.SolrIndexSearcher.<init> Opening Searcher@74e6ef1e[collection1] main
[junit4] 2> 2322655 T8968 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74e6ef1e[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C3))}
[junit4] 2> 2322655 T8970 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095;done=true>>]
[junit4] 2> 2322655 T8970 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095
[junit4] 2> ASYNC NEW_CORE C2887 name=collection1 org.apache.solr.core.SolrCore@25031b53
[junit4] 2> 2322660 T8961 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52306/solr/replication&command=fetchindex} status=0 QTime=63
[junit4] 2> 2322664 T8962 C2887 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> 2322671 T8973 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2322671 T8973 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2322675 T8963 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2322676 T8973 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2322677 T8973 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2322677 T8973 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2322681 T8964 C2887 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> 2322682 T8973 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 2322682 T8973 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164
[junit4] 2> 2322683 T8973 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2322683 T8973 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2322683 T8973 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2322683 T8973 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@765af990 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a1fd01) fullCopy=false
[junit4] 2> 2322686 T8965 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322689 T8966 C2887 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> 2322693 T8961 C2887 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> 2322696 T8962 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322699 T8963 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2888 name=collection1 org.apache.solr.core.SolrCore@25031b53
[junit4] 2> 2322715 T8964 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322719 T8965 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322722 T8966 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322725 T8961 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322728 T8962 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322729 T8973 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2322731 T8973 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2322731 T8973 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2322732 T8973 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_2,generation=2}
[junit4] 2> 2322733 T8973 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2322733 T8973 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2322734 T8973 oass.SolrIndexSearcher.<init> Opening Searcher@330ffc58[collection1] main
[junit4] 2> 2322734 T8956 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@330ffc58[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C3))}
[junit4] 2> 2322734 T8973 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164;done=true>>]
[junit4] 2> 2322735 T8973 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164
[junit4] 2> ASYNC NEW_CORE C2889 name=collection1 org.apache.solr.core.SolrCore@6ec1f1d6
[junit4] 2> 2322735 T8949 C2889 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52309/solr/replication&command=fetchindex} status=0 QTime=66
[junit4] 2> 2322740 T8950 C2889 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> ASYNC NEW_CORE C2890 name=collection1 org.apache.solr.core.SolrCore@41c26dd6
[junit4] 2> 2322743 T8929 C2890 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> 2322746 T8924 C2890 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> 2322751 T8963 C2888 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:1980)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:772)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[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:136)
[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> 2322753 T8963 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
[junit4] 2> 2322758 T8925 C2890 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> 2322761 T8964 C2888 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> 2322765 T8965 C2888 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:1980)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:772)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[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:136)
[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> 2322766 T8965 C2888 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> 2322771 T8966 C2888 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:1980)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:772)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[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:136)
[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> 2322772 T8966 C2888 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> 2322780 T8961 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 2322781 T8951 C2889 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> 2322784 T8962 C2888 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> 2322787 T8953 C2889 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> 2322790 T8963 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 2322794 T8964 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2322797 T8965 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2322800 T8966 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 2322803 T8961 C2888 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2322805 T8961 C2888 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_3,generation=3}
[junit4] 2> 2322805 T8961 C2888 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2322806 T8961 C2888 oass.SolrIndexSearcher.<init> Opening Searcher@7b83db75[collection1] main
[junit4] 2> 2322806 T8961 C2888 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2322806 T8968 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b83db75[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):C4))}
[junit4] 2> 2322807 T8961 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 4
[junit4] 2> 2322809 T8975 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2322810 T8975 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2322812 T8926 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2322813 T8975 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2322813 T8975 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 2322813 T8975 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2322817 T8928 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2
[junit4] 2> 2322817 T8975 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 2322818 T8975 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221300
[junit4] 2> 2322818 T8975 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@67092dff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@435744b) fullCopy=true
[junit4] 2> 2322820 T8929 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322824 T8924 C2890 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> 2322827 T8925 C2890 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> 2322832 T8927 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322836 T8926 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322841 T8928 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=1
[junit4] 2> 2322844 T8929 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322848 T8924 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322851 T8925 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 2322854 T8927 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status
[...truncated too long message...]
enkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index;done=false>>]
[junit4] 2> 2364908 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index
[junit4] 2> 2364908 T8920 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2364978 T8920 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=639096192
[junit4] 2> 2364979 T8920 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1dbdc8ff
[junit4] 2> 2364981 T8920 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> 2364981 T8920 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2364982 T8920 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2364983 T8920 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2364984 T8920 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2364985 T8920 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2364986 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data;done=false>>]
[junit4] 2> 2364987 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
[junit4] 2> 2364988 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index;done=false>>]
[junit4] 2> 2364989 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
[junit4] 2> 2364990 T8920 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2365074 T8920 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=sl_SI, timezone=Africa/Blantyre
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=137728232,total=343605248
[junit4] 2> NOTE: All tests run in this JVM: [LoggingHandlerTest, ScriptEngineTest, BasicFunctionalityTest, TestUpdate, DistanceFunctionTest, TestFieldTypeCollectionResource, TestRemoteStreaming, TestSchemaResource, ResponseHeaderTest, TestArbitraryIndexDir, TestDocumentBuilder, TestExceedMaxTermLength, FullSolrCloudDistribCmdsTest, TestCustomSort, HardAutoCommitTest, NoCacheHeaderTest, OpenCloseCoreStressTest, TestBM25SimilarityFactory, TestConfigSets, TestJmxIntegration, DistributedDebugComponentTest, DeleteShardTest, FieldFacetTest, SolrIndexSplitterTest, OpenExchangeRatesOrgProviderTest, QueryResultKeyTest, TestUniqueKeyFieldResource, TestSerializedLuceneMatchVersion, SolrRequestParserTest, DocValuesMultiTest, RequestHandlersTest, TestFastOutputStream, TestRandomFaceting, TestSearchPerf, OutputWriterTest, CustomCollectionTest, BadComponentTest, FileUtilsTest, TestJmxMonitoredMap, TestSolrXml, CopyFieldTest, AutoCommitTest, TestSolrXMLSerializer, HdfsUnloadDistributedZkTest, TestValueSourceCache, TestRequestStatusCollectionAPI, FieldAnalysisRequestHandlerTest, TestPhraseSuggestions, TestHighFrequencyDictionaryFactory, TestRandomMergePolicy, ResponseLogComponentTest, FileBasedSpellCheckerTest, TestQueryUtils, URLClassifyProcessorTest, TestBlendedInfixSuggestions, HttpPartitionTest, TestDistribDocBasedVersion, TestManagedResource, DOMUtilTest, TestDefaultSimilarityFactory, TestSuggestSpellingConverter, TestDistributedSearch, TestSolrJ, TestReversedWildcardFilterFactory, TestStressVersions, HdfsRecoveryZkTest, TestLazyCores, SynonymTokenizerTest, TestFieldCollectionResource, NumericFieldsTest, IndexSchemaRuntimeFieldTest, PluginInfoTest, TestIBSimilarityFactory, TestMaxScoreQueryParser, PolyFieldTest, TestBinaryField, SolrIndexConfigTest, AsyncMigrateRouteKeyTest, TermsComponentTest, CoreAdminCreateDiscoverTest, ShardSplitTest, TestSurroundQueryParser, SliceStateUpdateTest, SolrTestCaseJ4Test, SuggesterWFSTTest, DistributedQueryElevationComponentTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestOmitPositions, TestSolrDeletionPolicy2, LukeRequestHandlerTest, AliasIntegrationTest, SortByFunctionTest, TermVectorComponentDistributedTest, XmlUpdateRequestHandlerTest, TestQueryTypes, OverseerRolesTest, ChangedSchemaMergeTest, HdfsDirectoryTest, OverseerTest, HdfsWriteToMultipleCollectionsTest, TestLMJelinekMercerSimilarityFactory, ReturnFieldsTest, ZkNodePropsTest, TestRangeQuery, CircularListTest, TestMergePolicyConfig, DistributedQueryComponentCustomSortTest, CoreContainerCoreInitFailuresTest, TestSort, TestIndexingPerformance, EchoParamsTest, TestDistributedGrouping, DeleteInactiveReplicaTest, RemoteQueryErrorTest, CurrencyFieldOpenExchangeTest, DisMaxRequestHandlerTest, TestSolrCoreProperties, TimeZoneUtilsTest, TestDynamicFieldCollectionResource, FieldFacetExtrasTest, DefaultValueUpdateProcessorTest, XsltUpdateRequestHandlerTest, AbstractAnalyticsStatsTest, NoFacetTest, TestTrie, SchemaVersionSpecificBehaviorTest, SOLR749Test, SignatureUpdateProcessorFactoryTest, LeaderElectionIntegrationTest, TestRTGBase, CurrencyFieldXmlFileTest, DistributedTermsComponentTest, TestManagedStopFilterFactory, TestCollectionAPI, CursorMarkTest, ChaosMonkeySafeLeaderTest, DirectUpdateHandlerTest, TestLuceneMatchVersion, RecoveryZkTest, RangeFacetTest, TestManagedSynonymFilterFactory, TestCopyFieldCollectionResource, DateFieldTest, DirectSolrSpellCheckerTest, TestJoin, TestShardHandlerFactory, TestCodecSupport, EnumFieldTest, HdfsBasicDistributedZk2Test, RankQueryTest, DebugComponentTest, TestFunctionQuery, TestModifyConfFiles, SolrCmdDistributorTest, TestLRUCache, DirectUpdateHandlerOptimizeTest, ModifyConfFileTest, DistribCursorPagingTest, ClusterStateUpdateTest, HdfsBasicDistributedZkTest, RAMDirectoryFactoryTest, TestPseudoReturnFields, ReplicationFactorTest, ZkSolrClientTest, HdfsChaosMonkeySafeLeaderTest, UniqFieldsUpdateProcessorFactoryTest, SolrXmlInZkTest, RegexBoostProcessorTest, TestAnalyzeInfixSuggestions, SearchHandlerTest, BasicDistributedZk2Test, DocValuesTest, TestSweetSpotSimilarityFactory, TestManagedSchemaFieldResource, TestCloudManagedSchemaAddField, QueryEqualityTest, TestSolrQueryParser, TestOverriddenPrefixQueryForCustomFieldType, MultiThreadedOCPTest, TestDynamicFieldResource, SuggesterTest, TestIndexSearcher, TestLMDirichletSimilarityFactory, TestUtils, TestReloadAndDeleteDocs, TestSolr4Spatial, DirectSolrConnectionTest, SpellPossibilityIteratorTest, CursorPagingTest, AddBlockUpdateTest, TestXIncludeConfig, TestFastWriter, TestPerFieldSimilarity, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, TestReplicationHandler]
[junit4] Completed in 43.15s, 14 tests, 1 error <<< FAILURES!
[...truncated 604 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1296: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:920: There were test failures: 406 suites, 1688 tests, 1 error, 41 ignored (14 assumptions)
Total time: 103 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
Re: [JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build #
1605 - Failure!
Posted by Dawid Weiss <da...@cs.put.poznan.pl>.
I fixed the NPE problem in rm(); potentially could be triggered if
race conditions on folder removals are present (as they are in this
test).
Dawid
On Fri, May 30, 2014 at 10:29 AM, Policeman Jenkins Server
<je...@thetaphi.de> wrote:
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1605/
> Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
>
> 1 tests failed.
> REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
>
> Error Message:
>
>
> Stack Trace:
> java.lang.NullPointerException
> at __randomizedtesting.SeedInfo.seed([C3F97C04E5DE72DD:82725C61C2608192]:0)
> at org.apache.lucene.util.TestUtil.rm(TestUtil.java:123)
> at org.apache.lucene.util.TestUtil.rm(TestUtil.java:126)
> at org.apache.lucene.util.TestUtil.rm(TestUtil.java:110)
> at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1556)
> 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.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 11228 lines...]
> [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
> [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/init-core-data-001
> [junit4] 2> 2321963 T8920 oas.SolrTestCaseJ4.setUp ###Starting doTestRepeater
> [junit4] 2> 2322001 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 2322006 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52306
> [junit4] 2> 2322007 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
> [junit4] 2> 2322008 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 2322008 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001
> [junit4] 2> 2322009 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/'
> [junit4] 2> 2322054 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/solr.xml
> [junit4] 2> 2322068 T8920 oasc.CoreContainer.<init> New CoreContainer 1371501479
> [junit4] 2> 2322068 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/]
> [junit4] 2> 2322069 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 2322069 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 2322069 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 2322070 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 2322070 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 2322070 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 2322071 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 2322071 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 2322071 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 2322073 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 2322073 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 2322073 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
> [junit4] 2> 2322081 T8930 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/'
> [junit4] 2> 2322104 T8930 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
> [junit4] 2> 2322112 T8930 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 2322113 T8930 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
> [junit4] 2> 2322115 T8930 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 2322120 T8930 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 2322121 T8930 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/
> [junit4] 2> 2322122 T8930 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 2322123 T8930 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/
> [junit4] 2> 2322123 T8930 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 2322123 T8930 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data
> [junit4] 2> 2322124 T8930 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index/
> [junit4] 2> 2322124 T8930 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 2322125 T8930 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index
> [junit4] 2> 2322125 T8930 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
> [junit4] 2> 2322125 T8930 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_1,generation=1}
> [junit4] 2> 2322126 T8930 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322127 T8930 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
> [junit4] 2> 2322128 T8930 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
> [junit4] 2> 2322129 T8930 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
> [junit4] 2> 2322129 T8930 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 2322137 T8930 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2322138 T8930 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 2322138 T8930 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 2322139 T8930 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
> [junit4] 2> 2322139 T8930 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_1,generation=1}
> [junit4] 2> 2322139 T8930 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322139 T8930 oass.SolrIndexSearcher.<init> Opening Searcher@147a8b2a[collection1] main
> [junit4] 2> 2322140 T8930 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/conf
> [junit4] 2> 2322140 T8930 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/conf/}
> [junit4] 2> 2322140 T8930 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/conf
> [junit4] 2> 2322141 T8930 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
> [junit4] 2> 2322141 T8930 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
> [junit4] 2> 2322141 T8930 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 2322141 T8930 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
> [junit4] 2> 2322142 T8930 oash.ReplicationHandler.inform Commits will be reserved for 10000
> [junit4] 2> 2322142 T8930 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 2322142 T8931 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@147a8b2a[collection1] main{UninvertingDirectoryReader()}
> [junit4] 2> 2322144 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
> [junit4] 2> 2322144 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> 2322155 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 2322159 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52307
> [junit4] 2> 2322161 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
> [junit4] 2> 2322162 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 2322162 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002
> [junit4] 2> 2322162 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/'
> [junit4] 2> 2322183 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/solr.xml
> [junit4] 2> 2322191 T8920 oasc.CoreContainer.<init> New CoreContainer 6824207
> [junit4] 2> 2322191 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/]
> [junit4] 2> 2322192 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 2322192 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 2322192 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 2322193 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 2322193 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 2322193 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 2322194 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 2322194 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 2322194 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 2322195 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 2322195 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 2322195 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
> [junit4] 2> 2322198 T8942 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/'
> [junit4] 2> 2322233 T8942 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
> [junit4] 2> 2322238 T8942 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 2322240 T8942 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
> [junit4] 2> 2322243 T8942 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 2322246 T8942 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 2322246 T8942 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/
> [junit4] 2> 2322246 T8942 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 2322247 T8942 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/
> [junit4] 2> 2322247 T8942 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 2322247 T8942 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
> [junit4] 2> 2322247 T8942 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index/
> [junit4] 2> 2322248 T8942 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 2322249 T8942 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
> [junit4] 2> 2322250 T8942 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
> [junit4] 2> 2322251 T8942 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7428385f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3aba1deb),segFN=segments_1,generation=1}
> [junit4] 2> 2322251 T8942 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322253 T8942 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 2322253 T8942 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
> [junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
> [junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
> [junit4] 2> 2322255 T8942 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 2322256 T8942 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 2322257 T8942 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2322259 T8942 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 2322259 T8942 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 2322259 T8942 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
> [junit4] 2> 2322260 T8942 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7428385f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3aba1deb),segFN=segments_1,generation=1}
> [junit4] 2> 2322260 T8942 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322260 T8942 oass.SolrIndexSearcher.<init> Opening Searcher@658125e7[collection1] main
> [junit4] 2> 2322260 T8942 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
> [junit4] 2> 2322261 T8942 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf/}
> [junit4] 2> 2322261 T8942 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
> [junit4] 2> 2322262 T8942 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
> [junit4] 2> 2322262 T8942 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
> [junit4] 2> 2322262 T8942 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 2322271 T8942 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
> [junit4] 2> 2322271 T8942 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 2322271 T8943 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@658125e7[collection1] main{UninvertingDirectoryReader()}
> [junit4] 2> 2322272 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
> [junit4] 2> 2322273 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> 2322290 T8920 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6824207
> [junit4] 2> 2322292 T8920 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@777e6ff
> [junit4] 2> 2322292 T8920 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> 2322293 T8920 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
> [junit4] 2> 2322293 T8920 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4] 2> 2322294 T8920 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
> [junit4] 2> 2322294 T8920 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4] 2> 2322295 T8920 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
> [junit4] 2> 2322295 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data;done=false>>]
> [junit4] 2> 2322295 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
> [junit4] 2> 2322296 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index;done=false>>]
> [junit4] 2> 2322296 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
> [junit4] 2> 2322297 T8920 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
> [junit4] 2> 2322351 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 2322357 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52308
> [junit4] 2> 2322359 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
> [junit4] 2> 2322359 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 2322359 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002
> [junit4] 2> 2322359 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/'
> [junit4] 2> 2322379 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/solr.xml
> [junit4] 2> 2322385 T8920 oasc.CoreContainer.<init> New CoreContainer 1536040162
> [junit4] 2> 2322386 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/]
> [junit4] 2> 2322386 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 2322387 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 2322387 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 2322388 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 2322388 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 2322389 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 2322390 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 2322391 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 2322391 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
> [junit4] 2> 2322395 T8955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/'
> [junit4] 2> 2322422 T8955 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
> [junit4] 2> 2322428 T8955 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 2322428 T8955 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
> [junit4] 2> 2322432 T8955 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 2322434 T8955 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 2322434 T8955 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/
> [junit4] 2> 2322435 T8955 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 2322435 T8955 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/
> [junit4] 2> 2322435 T8955 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 2322436 T8955 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
> [junit4] 2> 2322436 T8955 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index/
> [junit4] 2> 2322436 T8955 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 2322437 T8955 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
> [junit4] 2> 2322438 T8955 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
> [junit4] 2> 2322439 T8955 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_1,generation=1}
> [junit4] 2> 2322439 T8955 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322440 T8955 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 2322440 T8955 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 2322441 T8955 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
> [junit4] 2> 2322441 T8955 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
> [junit4] 2> 2322441 T8955 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
> [junit4] 2> 2322442 T8955 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 2322442 T8955 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 2322443 T8955 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2322444 T8955 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 2322445 T8955 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 2322445 T8955 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
> [junit4] 2> 2322445 T8955 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_1,generation=1}
> [junit4] 2> 2322445 T8955 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322446 T8955 oass.SolrIndexSearcher.<init> Opening Searcher@24be5a09[collection1] main
> [junit4] 2> 2322446 T8955 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
> [junit4] 2> 2322446 T8955 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf/}
> [junit4] 2> 2322447 T8955 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/conf
> [junit4] 2> 2322447 T8955 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
> [junit4] 2> 2322447 T8955 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
> [junit4] 2> 2322447 T8955 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 2322448 T8955 oash.ReplicationHandler.inform Commits will be reserved for 10000
> [junit4] 2> 2322448 T8955 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 2322448 T8956 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24be5a09[collection1] main{UninvertingDirectoryReader()}
> [junit4] 2> 2322449 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
> [junit4] 2> 2322450 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> 2322460 T8920 oejs.Server.doStart jetty-8.1.10.v20130312
> [junit4] 2> 2322467 T8920 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52309
> [junit4] 2> 2322469 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
> [junit4] 2> 2322469 T8920 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 2322469 T8920 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003
> [junit4] 2> 2322469 T8920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/'
> [junit4] 2> 2322498 T8920 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/solr.xml
> [junit4] 2> 2322507 T8920 oasc.CoreContainer.<init> New CoreContainer 587829046
> [junit4] 2> 2322508 T8920 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/]
> [junit4] 2> 2322508 T8920 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
> [junit4] 2> 2322509 T8920 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
> [junit4] 2> 2322509 T8920 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
> [junit4] 2> 2322510 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4] 2> 2322510 T8920 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4] 2> 2322510 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4] 2> 2322511 T8920 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4] 2> 2322511 T8920 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4] 2> 2322511 T8920 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4] 2> 2322512 T8920 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 2322513 T8920 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 2322513 T8920 oasc.CoreContainer.load Host Name: 127.0.0.1
> [junit4] 2> 2322516 T8967 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/'
> [junit4] 2> 2322544 T8967 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
> [junit4] 2> 2322551 T8967 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
> [junit4] 2> 2322553 T8967 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
> [junit4] 2> 2322555 T8967 oass.IndexSchema.readSchema [collection1] Schema name=test
> [junit4] 2> 2322558 T8967 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 2322558 T8967 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/
> [junit4] 2> 2322559 T8967 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
> [junit4] 2> 2322559 T8967 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/
> [junit4] 2> 2322559 T8967 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4] 2> 2322560 T8967 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data
> [junit4] 2> 2322560 T8967 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index/
> [junit4] 2> 2322561 T8967 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index' doesn't exist. Creating new index...
> [junit4] 2> 2322562 T8967 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index
> [junit4] 2> 2322562 T8967 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=61.63671875, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=29.454642775407518, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
> [junit4] 2> 2322563 T8967 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_1,generation=1}
> [junit4] 2> 2322563 T8967 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322564 T8967 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 2322564 T8967 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
> [junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
> [junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
> [junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4] 2> 2322565 T8967 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
> [junit4] 2> 2322568 T8967 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2322569 T8967 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 2322569 T8967 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 2322569 T8967 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7123357152034614]
> [junit4] 2> 2322570 T8967 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_1,generation=1}
> [junit4] 2> 2322570 T8967 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2322570 T8967 oass.SolrIndexSearcher.<init> Opening Searcher@303be6b2[collection1] main
> [junit4] 2> 2322570 T8967 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/conf
> [junit4] 2> 2322571 T8967 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/conf/}
> [junit4] 2> 2322571 T8967 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/conf
> [junit4] 2> 2322572 T8967 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
> [junit4] 2> 2322572 T8967 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
> [junit4] 2> 2322572 T8967 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 2322573 T8967 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
> [junit4] 2> 2322573 T8967 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
> [junit4] 2> 2322574 T8967 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
> [junit4] 2> 2322574 T8967 oash.ReplicationHandler.inform Commits will be reserved for 10000
> [junit4] 2> 2322574 T8967 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 2322574 T8968 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@303be6b2[collection1] main{UninvertingDirectoryReader()}
> [junit4] 2> 2322575 T8920 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
> [junit4] 2> 2322575 T8920 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
> [junit4] 2> ASYNC NEW_CORE C2886 name=collection1 org.apache.solr.core.SolrCore@41c26dd6
> [junit4] 2> 2322579 T8925 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
> [junit4] 2> 2322582 T8926 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
> [junit4] 2> 2322585 T8927 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
> [junit4] 2> 2322588 T8928 C2886 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 2322590 T8928 C2886 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_1,generation=1}
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45f5be49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a79fdd),segFN=segments_2,generation=2}
> [junit4] 2> 2322591 T8928 C2886 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
> [junit4] 2> 2322592 T8928 C2886 oass.SolrIndexSearcher.<init> Opening Searcher@21c200ec[collection1] main
> [junit4] 2> 2322593 T8928 C2886 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4] 2> 2322593 T8931 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21c200ec[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C3))}
> [junit4] 2> 2322594 T8928 C2886 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 6
> [junit4] 2> 2322598 T8970 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
> [junit4] 2> 2322599 T8970 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
> [junit4] 2> 2322601 T8929 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
> [junit4] 2> 2322603 T8970 oash.SnapPuller.fetchLatestIndex Master's generation: 2
> [junit4] 2> 2322603 T8970 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
> [junit4] 2> 2322603 T8970 oash.SnapPuller.fetchLatestIndex Starting replication process
> [junit4] 2> 2322612 T8924 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=7
> [junit4] 2> 2322613 T8970 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
> [junit4] 2> 2322613 T8970 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095
> [junit4] 2> 2322613 T8970 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
> [junit4] 2> 2322613 T8970 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
> [junit4] 2> 2322614 T8970 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
> [junit4] 2> 2322614 T8970 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@1a4d48a9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6eda2a0c) fullCopy=false
> [junit4] 2> 2322616 T8925 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322621 T8926 C2886 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> 2322625 T8927 C2886 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> 2322629 T8928 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=1
> [junit4] 2> 2322632 T8929 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322635 T8924 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322639 T8925 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322642 T8926 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322645 T8927 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322648 T8928 C2886 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322649 T8970 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
> [junit4] 2> 2322651 T8970 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
> [junit4] 2> 2322651 T8970 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
> [junit4] 2> 2322653 T8970 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_1,generation=1}
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_2,generation=2}
> [junit4] 2> 2322654 T8970 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
> [junit4] 2> 2322654 T8970 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
> [junit4] 2> 2322655 T8970 oass.SolrIndexSearcher.<init> Opening Searcher@74e6ef1e[collection1] main
> [junit4] 2> 2322655 T8968 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74e6ef1e[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C3))}
> [junit4] 2> 2322655 T8970 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095;done=true>>]
> [junit4] 2> 2322655 T8970 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221095
> [junit4] 2> ASYNC NEW_CORE C2887 name=collection1 org.apache.solr.core.SolrCore@25031b53
> [junit4] 2> 2322660 T8961 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52306/solr/replication&command=fetchindex} status=0 QTime=63
> [junit4] 2> 2322664 T8962 C2887 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> 2322671 T8973 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
> [junit4] 2> 2322671 T8973 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
> [junit4] 2> 2322675 T8963 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
> [junit4] 2> 2322676 T8973 oash.SnapPuller.fetchLatestIndex Master's generation: 2
> [junit4] 2> 2322677 T8973 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
> [junit4] 2> 2322677 T8973 oash.SnapPuller.fetchLatestIndex Starting replication process
> [junit4] 2> 2322681 T8964 C2887 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> 2322682 T8973 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
> [junit4] 2> 2322682 T8973 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164
> [junit4] 2> 2322683 T8973 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
> [junit4] 2> 2322683 T8973 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
> [junit4] 2> 2322683 T8973 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
> [junit4] 2> 2322683 T8973 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@765af990 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a1fd01) fullCopy=false
> [junit4] 2> 2322686 T8965 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322689 T8966 C2887 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> 2322693 T8961 C2887 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> 2322696 T8962 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322699 T8963 C2887 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> ASYNC NEW_CORE C2888 name=collection1 org.apache.solr.core.SolrCore@25031b53
> [junit4] 2> 2322715 T8964 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322719 T8965 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322722 T8966 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322725 T8961 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322728 T8962 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322729 T8973 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
> [junit4] 2> 2322731 T8973 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
> [junit4] 2> 2322731 T8973 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
> [junit4] 2> 2322732 T8973 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_1,generation=1}
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@424ec666 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f442c),segFN=segments_2,generation=2}
> [junit4] 2> 2322733 T8973 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
> [junit4] 2> 2322733 T8973 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
> [junit4] 2> 2322734 T8973 oass.SolrIndexSearcher.<init> Opening Searcher@330ffc58[collection1] main
> [junit4] 2> 2322734 T8956 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@330ffc58[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C3))}
> [junit4] 2> 2322734 T8973 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164;done=true>>]
> [junit4] 2> 2322735 T8973 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index.20140530100221164
> [junit4] 2> ASYNC NEW_CORE C2889 name=collection1 org.apache.solr.core.SolrCore@6ec1f1d6
> [junit4] 2> 2322735 T8949 C2889 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52309/solr/replication&command=fetchindex} status=0 QTime=66
> [junit4] 2> 2322740 T8950 C2889 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> ASYNC NEW_CORE C2890 name=collection1 org.apache.solr.core.SolrCore@41c26dd6
> [junit4] 2> 2322743 T8929 C2890 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> 2322746 T8924 C2890 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> 2322751 T8963 C2888 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:1980)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:772)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
> [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:136)
> [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> 2322753 T8963 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
> [junit4] 2> 2322758 T8925 C2890 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> 2322761 T8964 C2888 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> 2322765 T8965 C2888 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:1980)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:772)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
> [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:136)
> [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> 2322766 T8965 C2888 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> 2322771 T8966 C2888 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:1980)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:772)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)
> [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
> [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:136)
> [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> 2322772 T8966 C2888 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> 2322780 T8961 C2888 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
> [junit4] 2> 2322781 T8951 C2889 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> 2322784 T8962 C2888 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> 2322787 T8953 C2889 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> 2322790 T8963 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
> [junit4] 2> 2322794 T8964 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
> [junit4] 2> 2322797 T8965 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
> [junit4] 2> 2322800 T8966 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
> [junit4] 2> 2322803 T8961 C2888 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 2322805 T8961 C2888 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_2,generation=2}
> [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@64825722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3def9e64),segFN=segments_3,generation=3}
> [junit4] 2> 2322805 T8961 C2888 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
> [junit4] 2> 2322806 T8961 C2888 oass.SolrIndexSearcher.<init> Opening Searcher@7b83db75[collection1] main
> [junit4] 2> 2322806 T8961 C2888 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4] 2> 2322806 T8968 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b83db75[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):C4))}
> [junit4] 2> 2322807 T8961 C2888 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 4
> [junit4] 2> 2322809 T8975 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
> [junit4] 2> 2322810 T8975 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
> [junit4] 2> 2322812 T8926 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
> [junit4] 2> 2322813 T8975 oash.SnapPuller.fetchLatestIndex Master's generation: 2
> [junit4] 2> 2322813 T8975 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
> [junit4] 2> 2322813 T8975 oash.SnapPuller.fetchLatestIndex Starting replication process
> [junit4] 2> 2322817 T8928 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2
> [junit4] 2> 2322817 T8975 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
> [junit4] 2> 2322818 T8975 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-003/collection1/data/index.20140530100221300
> [junit4] 2> 2322818 T8975 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@67092dff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@435744b) fullCopy=true
> [junit4] 2> 2322820 T8929 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322824 T8924 C2890 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> 2322827 T8925 C2890 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> 2322832 T8927 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322836 T8926 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322841 T8928 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.dnvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=1
> [junit4] 2> 2322844 T8929 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322848 T8924 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322851 T8925 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
> [junit4] 2> 2322854 T8927 C2890 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status
>
> [...truncated too long message...]
>
> enkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index;done=false>>]
> [junit4] 2> 2364908 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-001/collection1/data/index
> [junit4] 2> 2364908 T8920 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
> [junit4] 2> 2364978 T8920 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=639096192
> [junit4] 2> 2364979 T8920 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1dbdc8ff
> [junit4] 2> 2364981 T8920 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> 2364981 T8920 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
> [junit4] 2> 2364982 T8920 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4] 2> 2364983 T8920 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
> [junit4] 2> 2364984 T8920 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4] 2> 2364985 T8920 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
> [junit4] 2> 2364986 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data;done=false>>]
> [junit4] 2> 2364987 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data
> [junit4] 2> 2364988 T8920 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index;done=false>>]
> [junit4] 2> 2364989 T8920 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001/solr-instance-002/collection1/data/index
> [junit4] 2> 2364990 T8920 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
> [junit4] 2> 2365074 T8920 oas.SolrTestCaseJ4.deleteCore ###deleteCore
> [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.TestReplicationHandler-C3F97C04E5DE72DD-001
> [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=sl_SI, timezone=Africa/Blantyre
> [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=137728232,total=343605248
> [junit4] 2> NOTE: All tests run in this JVM: [LoggingHandlerTest, ScriptEngineTest, BasicFunctionalityTest, TestUpdate, DistanceFunctionTest, TestFieldTypeCollectionResource, TestRemoteStreaming, TestSchemaResource, ResponseHeaderTest, TestArbitraryIndexDir, TestDocumentBuilder, TestExceedMaxTermLength, FullSolrCloudDistribCmdsTest, TestCustomSort, HardAutoCommitTest, NoCacheHeaderTest, OpenCloseCoreStressTest, TestBM25SimilarityFactory, TestConfigSets, TestJmxIntegration, DistributedDebugComponentTest, DeleteShardTest, FieldFacetTest, SolrIndexSplitterTest, OpenExchangeRatesOrgProviderTest, QueryResultKeyTest, TestUniqueKeyFieldResource, TestSerializedLuceneMatchVersion, SolrRequestParserTest, DocValuesMultiTest, RequestHandlersTest, TestFastOutputStream, TestRandomFaceting, TestSearchPerf, OutputWriterTest, CustomCollectionTest, BadComponentTest, FileUtilsTest, TestJmxMonitoredMap, TestSolrXml, CopyFieldTest, AutoCommitTest, TestSolrXMLSerializer, HdfsUnloadDistributedZkTest, TestValueSourceCache, TestRequestStatusCollectionAPI, FieldAnalysisRequestHandlerTest, TestPhraseSuggestions, TestHighFrequencyDictionaryFactory, TestRandomMergePolicy, ResponseLogComponentTest, FileBasedSpellCheckerTest, TestQueryUtils, URLClassifyProcessorTest, TestBlendedInfixSuggestions, HttpPartitionTest, TestDistribDocBasedVersion, TestManagedResource, DOMUtilTest, TestDefaultSimilarityFactory, TestSuggestSpellingConverter, TestDistributedSearch, TestSolrJ, TestReversedWildcardFilterFactory, TestStressVersions, HdfsRecoveryZkTest, TestLazyCores, SynonymTokenizerTest, TestFieldCollectionResource, NumericFieldsTest, IndexSchemaRuntimeFieldTest, PluginInfoTest, TestIBSimilarityFactory, TestMaxScoreQueryParser, PolyFieldTest, TestBinaryField, SolrIndexConfigTest, AsyncMigrateRouteKeyTest, TermsComponentTest, CoreAdminCreateDiscoverTest, ShardSplitTest, TestSurroundQueryParser, SliceStateUpdateTest, SolrTestCaseJ4Test, SuggesterWFSTTest, DistributedQueryElevationComponentTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestOmitPositions, TestSolrDeletionPolicy2, LukeRequestHandlerTest, AliasIntegrationTest, SortByFunctionTest, TermVectorComponentDistributedTest, XmlUpdateRequestHandlerTest, TestQueryTypes, OverseerRolesTest, ChangedSchemaMergeTest, HdfsDirectoryTest, OverseerTest, HdfsWriteToMultipleCollectionsTest, TestLMJelinekMercerSimilarityFactory, ReturnFieldsTest, ZkNodePropsTest, TestRangeQuery, CircularListTest, TestMergePolicyConfig, DistributedQueryComponentCustomSortTest, CoreContainerCoreInitFailuresTest, TestSort, TestIndexingPerformance, EchoParamsTest, TestDistributedGrouping, DeleteInactiveReplicaTest, RemoteQueryErrorTest, CurrencyFieldOpenExchangeTest, DisMaxRequestHandlerTest, TestSolrCoreProperties, TimeZoneUtilsTest, TestDynamicFieldCollectionResource, FieldFacetExtrasTest, DefaultValueUpdateProcessorTest, XsltUpdateRequestHandlerTest, AbstractAnalyticsStatsTest, NoFacetTest, TestTrie, SchemaVersionSpecificBehaviorTest, SOLR749Test, SignatureUpdateProcessorFactoryTest, LeaderElectionIntegrationTest, TestRTGBase, CurrencyFieldXmlFileTest, DistributedTermsComponentTest, TestManagedStopFilterFactory, TestCollectionAPI, CursorMarkTest, ChaosMonkeySafeLeaderTest, DirectUpdateHandlerTest, TestLuceneMatchVersion, RecoveryZkTest, RangeFacetTest, TestManagedSynonymFilterFactory, TestCopyFieldCollectionResource, DateFieldTest, DirectSolrSpellCheckerTest, TestJoin, TestShardHandlerFactory, TestCodecSupport, EnumFieldTest, HdfsBasicDistributedZk2Test, RankQueryTest, DebugComponentTest, TestFunctionQuery, TestModifyConfFiles, SolrCmdDistributorTest, TestLRUCache, DirectUpdateHandlerOptimizeTest, ModifyConfFileTest, DistribCursorPagingTest, ClusterStateUpdateTest, HdfsBasicDistributedZkTest, RAMDirectoryFactoryTest, TestPseudoReturnFields, ReplicationFactorTest, ZkSolrClientTest, HdfsChaosMonkeySafeLeaderTest, UniqFieldsUpdateProcessorFactoryTest, SolrXmlInZkTest, RegexBoostProcessorTest, TestAnalyzeInfixSuggestions, SearchHandlerTest, BasicDistributedZk2Test, DocValuesTest, TestSweetSpotSimilarityFactory, TestManagedSchemaFieldResource, TestCloudManagedSchemaAddField, QueryEqualityTest, TestSolrQueryParser, TestOverriddenPrefixQueryForCustomFieldType, MultiThreadedOCPTest, TestDynamicFieldResource, SuggesterTest, TestIndexSearcher, TestLMDirichletSimilarityFactory, TestUtils, TestReloadAndDeleteDocs, TestSolr4Spatial, DirectSolrConnectionTest, SpellPossibilityIteratorTest, CursorPagingTest, AddBlockUpdateTest, TestXIncludeConfig, TestFastWriter, TestPerFieldSimilarity, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, TestReplicationHandler]
> [junit4] Completed in 43.15s, 14 tests, 1 error <<< FAILURES!
>
> [...truncated 604 lines...]
> BUILD FAILED
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1296: The following error occurred while executing this line:
> /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:920: There were test failures: 406 suites, 1688 tests, 1 error, 41 ignored (14 assumptions)
>
> Total time: 103 minutes 8 seconds
> Build step 'Invoke Ant' marked build as failure
> Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
> Archiving artifacts
> Recording test results
> Email was triggered for: Failure
> Sending email for trigger: Failure
>
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org