[JENKINS] Lucene-Solr-NightlyTests-5.x - Build # 694 - Still Failing

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/694/

2 tests failed.
REGRESSION:  
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
        at __randomizedtesting.SeedInfo.seed([4711AD7639F6F7]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
        at __randomizedtesting.SeedInfo.seed([4711AD7639F6F7]:0)




Build Log:
[...truncated 11034 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/init-core-data-001
   [junit4]   2> 1547885 T7619 oas.SolrTestCaseJ4.setUp ###Starting 
doTestDetails
   [junit4]   2> 1547902 T7619 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1547913 T7619 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:43367
   [junit4]   2> 1547914 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1547915 T7619 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1547915 T7619 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001
   [junit4]   2> 1547915 T7619 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/'
   [junit4]   2> 1547963 T7619 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/solr.xml
   [junit4]   2> 1548072 T7619 oasc.CoreContainer.<init> New CoreContainer 
231193030
   [junit4]   2> 1548073 T7619 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/]
   [junit4]   2> 1548074 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1548074 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 1548075 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1548075 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1548075 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1548076 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1548076 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1548076 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1548077 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1548077 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1548078 T7619 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1548078 T7619 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1548079 T7619 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1548079 T7619 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1548084 T7629 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/'
   [junit4]   2> 1548139 T7629 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1548182 T7629 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1548183 T7629 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf/schema.xml
   [junit4]   2> 1548190 T7629 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1548207 T7629 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1548208 T7629 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/
   [junit4]   2> 1548209 T7629 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1548209 T7629 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/
   [junit4]   2> 1548209 T7629 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1548210 T7629 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data
   [junit4]   2> 1548210 T7629 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index/
   [junit4]   2> 1548210 T7629 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1548211 T7629 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index
   [junit4]   2> 1548211 T7629 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=5, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=45.2412109375, 
floorSegmentMB=2.087890625, forceMergeDeletesPctAllowed=3.6099600856064686, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=1.51953125, 
noCFSRatio=0.3231567203580047
   [junit4]   2> 1548212 T7629 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_1,generation=1}
   [junit4]   2> 1548212 T7629 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1548213 T7629 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1548214 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548214 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548214 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548215 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 1548215 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 1548215 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 1548216 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 1548216 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 1548217 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 1548217 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 1548217 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 1548218 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/properties: 
org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 1548218 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 1548218 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
   [junit4]   2> 1548219 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 1548219 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1548248 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1548248 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1548248 T7629 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1548249 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1548249 T7629 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 1548268 T7629 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548270 T7629 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548272 T7629 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548274 T7629 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548276 T7629 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1548277 T7629 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1548277 T7629 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=49.8701171875, 
floorSegmentMB=0.328125, forceMergeDeletesPctAllowed=3.234204313587825, 
segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1548278 T7629 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_1,generation=1}
   [junit4]   2> 1548278 T7629 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1548278 T7629 oass.SolrIndexSearcher.<init> Opening 
Searcher@24ab2e62[collection1] main
   [junit4]   2> 1548279 T7629 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf
   [junit4]   2> 1548279 T7629 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf}
   [junit4]   2> 1548280 T7629 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf
   [junit4]   2> 1548281 T7629 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1548281 T7629 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf
   [junit4]   2> 1548281 T7629 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1548282 T7629 oash.ReplicationHandler.inform Replication 
enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1548282 T7629 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1548282 T7629 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1548282 T7630 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@24ab2e62[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1548283 T7619 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
   [junit4]   2> 1548284 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 1548300 T7619 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1548305 T7619 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:43584
   [junit4]   2> 1548306 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1548306 T7619 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1548306 T7619 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002
   [junit4]   2> 1548307 T7619 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/'
   [junit4]   2> 1548352 T7619 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/solr.xml
   [junit4]   2> 1548434 T7619 oasc.CoreContainer.<init> New CoreContainer 
1527518381
   [junit4]   2> 1548435 T7619 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/]
   [junit4]   2> 1548436 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1548437 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 1548437 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1548437 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1548438 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1548438 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1548438 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1548439 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1548439 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1548440 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1548441 T7619 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1548441 T7619 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1548441 T7619 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1548442 T7619 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1548446 T7641 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/'
   [junit4]   2> 1548499 T7641 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1548542 T7641 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1548543 T7641 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/conf/schema.xml
   [junit4]   2> 1548550 T7641 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1548567 T7641 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1548568 T7641 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/
   [junit4]   2> 1548568 T7641 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1548568 T7641 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/
   [junit4]   2> 1548569 T7641 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1548569 T7641 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data
   [junit4]   2> 1548569 T7641 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index/
   [junit4]   2> 1548570 T7641 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1548571 T7641 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index
   [junit4]   2> 1548572 T7641 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=5, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=45.2412109375, 
floorSegmentMB=2.087890625, forceMergeDeletesPctAllowed=3.6099600856064686, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=1.51953125, 
noCFSRatio=0.3231567203580047
   [junit4]   2> 1548573 T7641 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_1,generation=1}
   [junit4]   2> 1548573 T7641 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1548574 T7641 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1548575 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548575 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548575 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548576 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1548576 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 1548576 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 1548577 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 1548577 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 1548578 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 1548578 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 1548578 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 1548579 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 1548579 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/properties: 
org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 1548579 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 1548580 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
   [junit4]   2> 1548580 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 1548580 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1548581 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1548581 T7641 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1548581 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1548581 T7641 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1548583 T7641 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548585 T7641 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548587 T7641 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548590 T7641 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1548592 T7641 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1548592 T7641 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1548593 T7641 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=49.8701171875, 
floorSegmentMB=0.328125, forceMergeDeletesPctAllowed=3.234204313587825, 
segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1548593 T7641 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_1,generation=1}
   [junit4]   2> 1548594 T7641 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1548594 T7641 oass.SolrIndexSearcher.<init> Opening 
Searcher@18f95768[collection1] main
   [junit4]   2> 1548595 T7641 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/conf
   [junit4]   2> 1548595 T7641 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/conf}
   [junit4]   2> 1548595 T7641 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/conf
   [junit4]   2> 1548596 T7641 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1548597 T7641 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/conf
   [junit4]   2> 1548597 T7641 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1548598 T7641 oash.SnapPuller.startExecutorService Poll 
Scheduled at an interval of 1000ms
   [junit4]   2> 1548598 T7642 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@18f95768[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1548598 T7641 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1548599 T7619 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
   [junit4]   2> 1548599 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C2416 name=collection1 
org.apache.solr.core.SolrCore@2516d7e
   [junit4]   2> 1548603 T7622 C2416 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select params={wt=javabin&q=*:*&sort=id+desc&version=2} 
hits=0 status=0 QTime=1 
   [junit4]   2> 1548606 T7622 C2416 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=details&version=2&qt=/replication&_trace=getDetails} 
status=0 QTime=0 
   [junit4]   2> 1548611 T7623 C2416 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&slave=false&command=details&version=2&qt=/replication} 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C2417 name=collection1 
org.apache.solr.core.SolrCore@3368dd78
   [junit4]   2> 1548612 T7634 C2417 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=details&version=2&qt=/replication&_trace=getDetails} 
status=0 QTime=4 
   [junit4]   2> 1548616 T7622 C2416 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 
0 1
   [junit4]   2> 1548618 T7622 C2416 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1548621 T7622 C2416 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_2,generation=2}
   [junit4]   2> 1548622 T7622 C2416 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 1548624 T7622 C2416 oass.SolrIndexSearcher.<init> Opening 
Searcher@6f39ae5d[collection1] main
   [junit4]   2> 1548625 T7622 C2416 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1548625 T7630 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6f39ae5d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c1)))}
   [junit4]   2> 1548626 T7622 C2416 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&softCommit=false&version=2&waitSearcher=true&commit=true} 
{commit=} 0 8
   [junit4]   2> 1548685 T7645 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1548687 T7645 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1548689 T7623 C2416 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1548690 T7645 oash.SnapPuller.fetchLatestIndex Master's 
generation: 2
   [junit4]   2> 1548690 T7645 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 1
   [junit4]   2> 1548690 T7645 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> ASYNC  NEW_CORE C2418 name=collection1 
org.apache.solr.core.SolrCore@2516d7e
   [junit4]   2> 1548714 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=filelist&version=2&qt=/replication&generation=2} 
status=0 QTime=21 
   [junit4]   2> 1548715 T7645 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 4
   [junit4]   2> 1548716 T7645 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721687
   [junit4]   2> 1548716 T7645 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1548716 T7645 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1548716 T7645 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1548717 T7645 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@3a0c82a6 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b557e10; 
maxCacheMB=0.6826171875 maxMergeSizeMB=0.3369140625)) fullCopy=false
   [junit4]   2> 1548720 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=2&file=segments_2}
 status=0 QTime=0 
   [junit4]   2> 1548724 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=2&file=_0.cfe}
 status=0 QTime=0 
   [junit4]   2> 1548727 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=2&file=_0.si}
 status=0 QTime=0 
   [junit4]   2> 1548730 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=2&file=_0.cfs}
 status=0 QTime=0 
   [junit4]   2> 1548731 T7645 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1548737 T7645 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1548738 T7645 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=19, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1548739 T7645 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_2,generation=2}
   [junit4]   2> 1548741 T7645 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 1548741 T7645 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1548743 T7645 oass.SolrIndexSearcher.<init> Opening 
Searcher@1ee7e5e0[collection1] main
   [junit4]   2> 1548743 T7642 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1ee7e5e0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c1)))}
   [junit4]   2> 1548744 T7645 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721687
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721687;done=true>>]
   [junit4]   2> 1548744 T7645 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721687
   [junit4]   2> ASYNC  NEW_CORE C2419 name=collection1 
org.apache.solr.core.SolrCore@3368dd78
   [junit4]   2> 1548744 T7635 C2419 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={command=fetchindex&wait=true&masterUrl=http://127.0.0.1:43367/solr/replication}
 status=0 QTime=60 
   [junit4]   2> 1548755 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&slave=false&command=details&version=2&qt=/replication} 
status=0 QTime=0 
   [junit4]   2> 1548756 T7634 C2419 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=details&version=2&qt=/replication&_trace=getDetails} 
status=0 QTime=4 
   [junit4]   2> 1548760 T7622 C2418 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 
0 1
   [junit4]   2> 1548762 T7622 C2418 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1548765 T7622 C2418 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_3,generation=3}
   [junit4]   2> 1548766 T7622 C2418 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 1548767 T7622 C2418 oass.SolrIndexSearcher.<init> Opening 
Searcher@23dcdb17[collection1] main
   [junit4]   2> 1548768 T7622 C2418 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1548768 T7630 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@23dcdb17[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c1)
 Uninverting(_1(5.0.0):c1)))}
   [junit4]   2> 1548768 T7622 C2418 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&softCommit=false&version=2&waitSearcher=true&commit=true} 
{commit=} 0 6
   [junit4]   2> 1548771 T7648 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1548772 T7648 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1548774 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1548775 T7648 oash.SnapPuller.fetchLatestIndex Master's 
generation: 3
   [junit4]   2> 1548775 T7648 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 2
   [junit4]   2> 1548775 T7648 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> 1548777 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=filelist&version=2&qt=/replication&generation=3} 
status=0 QTime=0 
   [junit4]   2> 1548778 T7648 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 7
   [junit4]   2> 1548779 T7648 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721750
   [junit4]   2> 1548780 T7648 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1548780 T7648 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1548781 T7648 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1548782 T7648 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@35f29e3d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b72d276; 
maxCacheMB=0.6826171875 maxMergeSizeMB=0.3369140625)) fullCopy=false
   [junit4]   2> 1548785 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=3&file=_1.si}
 status=0 QTime=0 
   [junit4]   2> 1548789 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=3&file=segments_3}
 status=0 QTime=0 
   [junit4]   2> 1548790 T7648 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.cfe because it already exists
   [junit4]   2> 1548792 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=3&file=_1.cfe}
 status=0 QTime=0 
   [junit4]   2> 1548793 T7648 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.si because it already exists
   [junit4]   2> 1548795 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=3&file=_1.cfs}
 status=0 QTime=0 
   [junit4]   2> 1548796 T7648 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.cfs because it already exists
   [junit4]   2> 1548796 T7648 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1548798 T7648 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1548799 T7648 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=19, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1548800 T7648 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_3,generation=3}
   [junit4]   2> 1548801 T7648 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 1548801 T7648 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1548803 T7648 oass.SolrIndexSearcher.<init> Opening 
Searcher@6b7076ec[collection1] main
   [junit4]   2> 1548803 T7642 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6b7076ec[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c1)
 Uninverting(_1(5.0.0):c1)))}
   [junit4]   2> 1548804 T7648 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721750
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721750;done=true>>]
   [junit4]   2> 1548804 T7648 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721750
   [junit4]   2> 1548804 T7635 C2419 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={command=fetchindex&wait=true&masterUrl=http://127.0.0.1:43367/solr/replication}
 status=0 QTime=33 
   [junit4]   2> 1548810 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&slave=false&command=details&version=2&qt=/replication} 
status=0 QTime=0 
   [junit4]   2> 1548811 T7634 C2419 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=details&version=2&qt=/replication&_trace=getDetails} 
status=0 QTime=4 
   [junit4]   2> 1548815 T7622 C2418 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 
0 1
   [junit4]   2> 1548817 T7622 C2418 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1548821 T7622 C2418 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=3
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@5be5ac0b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4382354f),segFN=segments_4,generation=4}
   [junit4]   2> 1548822 T7622 C2418 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 4
   [junit4]   2> 1548823 T7622 C2418 oass.SolrIndexSearcher.<init> Opening 
Searcher@59ebdb1c[collection1] main
   [junit4]   2> 1548823 T7622 C2418 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1548824 T7630 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@59ebdb1c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c1)
 Uninverting(_1(5.0.0):c1) Uninverting(_2(5.0.0):c1)))}
   [junit4]   2> 1548824 T7622 C2418 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&softCommit=false&version=2&waitSearcher=true&commit=true} 
{commit=} 0 7
   [junit4]   2> 1548828 T7650 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1548829 T7650 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1548831 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1548831 T7650 oash.SnapPuller.fetchLatestIndex Master's 
generation: 4
   [junit4]   2> 1548832 T7650 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 3
   [junit4]   2> 1548832 T7650 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> 1548834 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=filelist&version=2&qt=/replication&generation=4} 
status=0 QTime=0 
   [junit4]   2> 1548835 T7650 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 10
   [junit4]   2> 1548836 T7650 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721807
   [junit4]   2> 1548836 T7650 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1548836 T7650 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1548836 T7650 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1548838 T7650 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@9943b0a 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62b0126f; 
maxCacheMB=0.6826171875 maxMergeSizeMB=0.3369140625)) fullCopy=false
   [junit4]   2> 1548838 T7650 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.si because it already exists
   [junit4]   2> 1548841 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=4&file=segments_4}
 status=0 QTime=0 
   [junit4]   2> 1548842 T7650 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.cfe because it already exists
   [junit4]   2> 1548842 T7650 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.cfe because it already exists
   [junit4]   2> 1548845 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=4&file=_2.si}
 status=0 QTime=0 
   [junit4]   2> 1548846 T7650 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.si because it already exists
   [junit4]   2> 1548846 T7650 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.cfs because it already exists
   [junit4]   2> 1548849 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=4&file=_2.cfs}
 status=0 QTime=0 
   [junit4]   2> 1548850 T7650 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.cfs because it already exists
   [junit4]   2> 1548852 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=filestream&checksum=true&command=filecontent&qt=/replication&generation=4&file=_2.cfe}
 status=0 QTime=0 
   [junit4]   2> 1548853 T7650 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1548855 T7650 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1548856 T7650 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=19, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1548908 T7650 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@750436e8 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67b0f55c),segFN=segments_4,generation=4}
   [junit4]   2> 1548909 T7650 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 4
   [junit4]   2> 1548909 T7650 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1548912 T7650 oass.SolrIndexSearcher.<init> Opening 
Searcher@7263f98d[collection1] main
   [junit4]   2> 1548912 T7642 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7263f98d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c1)
 Uninverting(_1(5.0.0):c1) Uninverting(_2(5.0.0):c1)))}
   [junit4]   2> 1548912 T7650 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721807
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721807;done=true>>]
   [junit4]   2> 1548913 T7650 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index.20141206045721807
   [junit4]   2> 1548913 T7636 C2419 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={command=fetchindex&wait=true&masterUrl=http://127.0.0.1:43367/solr/replication}
 status=0 QTime=85 
   [junit4]   2> 1548930 T7619 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1548934 T7619 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:61059
   [junit4]   2> 1548935 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1548935 T7619 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1548936 T7619 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003
   [junit4]   2> 1548936 T7619 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/'
   [junit4]   2> 1548984 T7619 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/solr.xml
   [junit4]   2> 1549032 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1549033 T7643 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1549079 T7619 oasc.CoreContainer.<init> New CoreContainer 
1022407322
   [junit4]   2> 1549080 T7619 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/]
   [junit4]   2> 1549081 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1549081 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 1549082 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1549082 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1549082 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1549083 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1549083 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1549083 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1549084 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1549084 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1549085 T7619 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1549085 T7619 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1549086 T7619 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1549086 T7619 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1549091 T7661 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/'
   [junit4]   2> 1549152 T7661 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1549201 T7661 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1549202 T7661 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/conf/schema.xml
   [junit4]   2> 1549210 T7661 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1549229 T7661 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1549230 T7661 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/
   [junit4]   2> 1549230 T7661 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1549231 T7661 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/
   [junit4]   2> 1549231 T7661 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1549231 T7661 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data
   [junit4]   2> 1549232 T7661 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/index/
   [junit4]   2> 1549232 T7661 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1549232 T7661 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/index
   [junit4]   2> 1549233 T7661 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=5, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=45.2412109375, 
floorSegmentMB=2.087890625, forceMergeDeletesPctAllowed=3.6099600856064686, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=1.51953125, 
noCFSRatio=0.3231567203580047
   [junit4]   2> 1549234 T7661 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@a610184 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@66aa866e),segFN=segments_1,generation=1}
   [junit4]   2> 1549234 T7661 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1549235 T7661 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1549235 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1549236 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1549236 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1549236 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1549237 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 1549237 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 1549237 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 1549238 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 1549238 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 1549239 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 1549239 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 1549239 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 1549240 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/properties: 
org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 1549240 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 1549240 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
   [junit4]   2> 1549241 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 1549241 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1549241 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1549241 T7661 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1549242 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1549242 T7661 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1549261 T7661 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1549264 T7661 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1549266 T7661 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1549269 T7661 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1549272 T7661 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1549272 T7661 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1549272 T7661 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=49.8701171875, 
floorSegmentMB=0.328125, forceMergeDeletesPctAllowed=3.234204313587825, 
segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1549273 T7661 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@a610184 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@66aa866e),segFN=segments_1,generation=1}
   [junit4]   2> 1549273 T7661 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1549274 T7661 oass.SolrIndexSearcher.<init> Opening 
Searcher@56bdb812[collection1] main
   [junit4]   2> 1549274 T7661 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/conf
   [junit4]   2> 1549274 T7661 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/conf}
   [junit4]   2> 1549275 T7661 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/conf
   [junit4]   2> 1549276 T7661 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1549277 T7661 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/conf
   [junit4]   2> 1549277 T7661 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1549277 T7661 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1549277 T7661 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1549278 T7661 oash.ReplicationHandler.inform Replication 
enabled for following config files: schema.xml
   [junit4]   2> 1549278 T7661 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1549278 T7661 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1549278 T7662 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@56bdb812[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1549279 T7619 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
   [junit4]   2> 1549279 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 1549285 T7624 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&slave=false&command=details&version=2&qt=/replication} 
status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C2420 name=collection1 
org.apache.solr.core.SolrCore@3de85990
   [junit4]   2> 1549286 T7654 C2420 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=details&version=2&qt=/replication&_trace=getDetails} 
status=0 QTime=4 
   [junit4]   2> 1549287 T7619 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=1022407322
   [junit4]   2> 1549290 T7619 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@3de85990
   [junit4]   2> 1549290 T7619 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> 1549291 T7619 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1549291 T7619 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1549291 T7619 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1549292 T7619 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1549293 T7619 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1549293 T7619 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/index;done=false>>]
   [junit4]   2> 1549293 T7619 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data/index
   [junit4]   2> 1549294 T7619 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data;done=false>>]
   [junit4]   2> 1549294 T7619 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-003/collection1/data
   [junit4]   2> 1549295 T7619 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1550032 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1550033 T7643 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1551031 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1551032 T7643 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1552031 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1552032 T7643 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1553031 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1553032 T7643 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1554031 T7623 C2418 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 
QTime=0 
   [junit4]   2> 1554032 T7643 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1554293 T7619 oas.SolrTestCaseJ4.tearDown ###Ending 
doTestDetails
   [junit4]   2> 1554293 T7619 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=231193030
   [junit4]   2> 1554297 T7619 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@2516d7e
   [junit4]   2> 1554297 T7619 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=3,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1554299 T7619 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1554300 T7619 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1554300 T7619 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1554302 T7619 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1554303 T7619 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1554304 T7619 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data;done=false>>]
   [junit4]   2> 1554304 T7619 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data
   [junit4]   2> 1554305 T7619 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index;done=false>>]
   [junit4]   2> 1554305 T7619 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index
   [junit4]   2> 1554306 T7619 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1559298 T7619 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=1527518381
   [junit4]   2> 1559301 T7619 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@3368dd78
   [junit4]   2> 1570030 T7643 oash.SnapPuller.fetchLatestIndex ERROR Master 
at: http://127.0.0.1:43367/solr is not available. Index fetch failed. 
Exception: IOException occured when talking to server at: 
http://127.0.0.1:43367/solr
   [junit4]   2> 1570033 T7619 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> 1570033 T7619 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1570034 T7619 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1570034 T7619 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1570036 T7619 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1570037 T7619 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1570037 T7619 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index;done=false>>]
   [junit4]   2> 1570038 T7619 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data/index
   [junit4]   2> 1570038 T7619 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data;done=false>>]
   [junit4]   2> 1570039 T7619 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-002/collection1/data
   [junit4]   2> 1570040 T7619 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1574321 T7619 oas.SolrTestCaseJ4.setUp ###Starting 
doTestIndexAndConfigReplication
   [junit4]   2> 1574326 T7619 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1574329 T7619 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:36115
   [junit4]   2> 1574329 T7619 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1574330 T7619 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1574330 T7619 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001
   [junit4]   2> 1574330 T7619 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/'
   [junit4]   2> 1574344 T7619 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/solr.xml
   [junit4]   2> 1574416 T7619 oasc.CoreContainer.<init> New CoreContainer 
892647751
   [junit4]   2> 1574416 T7619 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/]
   [junit4]   2> 1574418 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1574418 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 1574418 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1574419 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1574419 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1574419 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1574420 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1574420 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1574420 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1574421 T7619 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1574421 T7619 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1574422 T7619 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1574422 T7619 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1574422 T7619 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1574428 T7673 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/'
   [junit4]   2> 1574467 T7673 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1574501 T7673 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1574501 T7673 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf/schema.xml
   [junit4]   2> 1574506 T7673 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1574517 T7673 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1574518 T7673 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/
   [junit4]   2> 1574518 T7673 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1574518 T7673 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/
   [junit4]   2> 1574518 T7673 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1574520 T7673 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data
   [junit4]   2> 1574520 T7673 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index/
   [junit4]   2> 1574520 T7673 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1574520 T7673 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/data/index
   [junit4]   2> 1574521 T7673 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=5, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=45.2412109375, 
floorSegmentMB=2.087890625, forceMergeDeletesPctAllowed=3.6099600856064686, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=1.51953125, 
noCFSRatio=0.3231567203580047
   [junit4]   2> 1574521 T7673 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@40f05360 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61f1e4ee),segFN=segments_1,generation=1}
   [junit4]   2> 1574522 T7673 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1574522 T7673 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1574523 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1574523 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1574523 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1574523 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 1574523 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 1574524 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 1574524 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 1574524 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 1574525 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 1574525 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 1574525 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 1574525 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/properties: 
org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 1574526 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 1574526 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
   [junit4]   2> 1574526 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 1574526 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1574526 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1574527 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1574527 T7673 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1574527 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1574527 T7673 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 1574529 T7673 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1574530 T7673 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1574532 T7673 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1574533 T7673 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1574535 T7673 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1574535 T7673 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1574536 T7673 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=49.8701171875, 
floorSegmentMB=0.328125, forceMergeDeletesPctAllowed=3.234204313587825, 
segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1574536 T7673 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@40f05360 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61f1e4ee),segFN=segments_1,generation=1}
   [junit4]   2> 1574536 T7673 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1574537 T7673 oass.SolrIndexSearcher.<init> Opening 
Searcher@7359d730[collection1] main
   [junit4]   2> 1574537 T7673 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/collection1/conf
   [junit4]   2> 1574537 T7673 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-4711AD7639F6F7-001/solr-instance-001/

[...truncated too long message...]

lrServer.java:116)
   [junit4]   2>                at 
org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
   [junit4]   2>                at 
org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:173)
   [junit4]   2>                at 
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1144)
   [junit4]   2>                at 
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2>                at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   [junit4]   2>                at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>                at 
java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
   [junit4]   2>                at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2>                at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>                at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>                at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>                at java.lang.Thread.run(Thread.java:745)
   [junit4]   2>           6) Thread[id=8488, name=Thread-4108, state=WAITING, 
group=TGRP-TestReplicationHandler]
   [junit4]   2>                at java.lang.Object.wait(Native Method)
   [junit4]   2>                at java.lang.Object.wait(Object.java:503)
   [junit4]   2>                at 
org.apache.solr.core.CloserThread.run(CoreContainer.java:924)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{name=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
newname=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{}, 
sim=DefaultSimilarity, locale=ja_JP_JP_#u-ca-japanese, timezone=Etc/GMT-9
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 
(64-bit)/cpus=16,threads=7,free=306206864,total=525336576
   [junit4]   2> NOTE: All tests run in this JVM: [EchoParamsTest, 
SuggesterTest, TestSchemaSimilarityResource, CollectionsAPIDistributedZkTest, 
TestCollapseQParserPlugin, SuggesterTSTTest, TestSolrConfigHandlerCloud, 
TestCloudPivotFacet, TestMergePolicyConfig, TestSolrConfigHandlerConcurrent, 
DOMUtilTest, OutputWriterTest, VMParamsZkACLAndCredentialsProvidersTest, 
ClusterStateUpdateTest, TestManagedResourceStorage, SolrInfoMBeanTest, 
TestConfigReload, AsyncMigrateRouteKeyTest, PluginInfoTest, 
SpellPossibilityIteratorTest, FieldMutatingUpdateProcessorTest, 
DistribCursorPagingTest, TestHighFrequencyDictionaryFactory, 
TestFreeTextSuggestions, TestLRUCache, TestInitParams, 
DocumentAnalysisRequestHandlerTest, CoreAdminHandlerTest, 
TestSolrXmlPersistence, IndexBasedSpellCheckerTest, TestDistributedGrouping, 
StandardRequestHandlerTest, MergeStrategyTest, TestSchemaManager, 
HdfsBasicDistributedZkTest, BlockDirectoryTest, BadIndexSchemaTest, 
TimeZoneUtilsTest, ResponseHeaderTest, DistributedDebugComponentTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, ResponseLogComponentTest, 
TermVectorComponentDistributedTest, EnumFieldTest, 
DistributedTermsComponentTest, TestCloudSchemaless, 
WordBreakSolrSpellCheckerTest, CoreContainerCoreInitFailuresTest, 
TestCSVLoader, FileBasedSpellCheckerTest, TestDocSet, 
LeaderInitiatedRecoveryOnCommitTest, SolrIndexSplitterTest, 
TestStandardQParsers, LeaderElectionTest, ShardRoutingTest, 
TestReplicationHandler]
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandler -Dtests.seed=4711AD7639F6F7 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=ja_JP_JP_#u-ca-japanese -Dtests.timezone=Etc/GMT-9 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J3 | TestReplicationHandler (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 
7200000 msec).
   [junit4]    >        at __randomizedtesting.SeedInfo.seed([4711AD7639F6F7]:0)
   [junit4] Completed on J3 in 7223.39s, 13 tests, 2 errors <<< FAILURES!

[...truncated 13 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:536:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:477:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:190:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:508:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1363:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:970:
 There were test failures: 450 suites, 1843 tests, 1 suite-level error, 1 
error, 56 ignored (10 assumptions)

Total time: 234 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #689
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 837177060 bytes
Compression is 0.0%
Took 2 min 24 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org