[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_66) - Build # 5430 - Failure!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5430/
Java: 32bit/jdk1.8.0_66 -server -XX:+UseSerialGC

2 tests failed.
FAILED:  org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup

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([A8423EFBC2AA7039]:0)


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

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

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




Build Log:
[...truncated 11024 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandlerBackup
   [junit4]   2> Creating dataDir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\init-core-data-001
   [junit4]   2> 343824 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testBackupOnCommit
   [junit4]   2> 343825 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1
   [junit4]   2> 343834 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 343838 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1ee7cf9{/solr,null,AVAILABLE}
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.e.j.s.ServerConnector Started 
ServerConnector@7a0761{HTTP/1.1}{127.0.0.1:51663}
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.e.j.s.Server Started @347347ms
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1\data,
 hostContext=/solr, hostPort=51663}
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@e2f2a
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001'
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr 
(NoInitialContextEx)
   [junit4]   2> 343841 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not 
find system property or JNDI)
   [junit4]   2> 343843 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\solr.xml
   [junit4]   2> 343848 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoresLocator Config-defined core root directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.
   [junit4]   2> 343848 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoreContainer New CoreContainer 18748845
   [junit4]   2> 343848 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001]
   [junit4]   2> 343848 WARN  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoreContainer Couldn't add files from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\lib
 to classpath: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\lib
   [junit4]   2> 343848 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 343850 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 343851 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 343851 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 343851 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 343851 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 343851 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoresLocator Looking for core definitions underneath 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.
   [junit4]   2> 343855 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, 
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1,
 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\,
 dataDir=data\}
   [junit4]   2> 343855 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoresLocator Found core collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\
   [junit4]   2> 343855 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 343855 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1'
   [junit4]   2> 343855 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 343855 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 343855 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.s.SolrDispatchFilter 
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 343855 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 343868 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 343870 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 343873 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 343875 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 343877 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 343882 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1'
   [junit4]   2> 343882 INFO  (coreLoadExecutor-2144-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1
   [junit4]   2> 343882 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 343882 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1],
 dataDir=[null]
   [junit4]   2> 343882 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 343883 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\
   [junit4]   2> 343883 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\index/
   [junit4]   2> 343883 WARN  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\index'
 doesn't exist. Creating new index...
   [junit4]   2> 343883 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\index
   [junit4]   2> 343883 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 343883 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3a54d4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17be39a),segFN=segments_1,generation=1}
   [junit4]   2> 343883 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 343884 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\
   [junit4]   2> 343885 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 343891 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 343892 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 343893 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 343893 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3a54d4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17be39a),segFN=segments_1,generation=1}
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5731a7[collection1] 
main
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1\conf
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1\conf}
   [junit4]   2> 343895 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\collection1\conf
   [junit4]   2> 343896 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 343896 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema-replication2.xml:schema.xml
   [junit4]   2> 343896 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 343897 INFO  
(searcherExecutor-2145-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5731a7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 343897 INFO  (coreLoadExecutor-2144-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 344313 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 344313 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {deleteByQuery=*:*} 0 0
   [junit4]   2> 344315 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[0]} 0 0
   [junit4]   2> 344317 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[1]} 0 0
   [junit4]   2> 344320 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[2]} 0 0
   [junit4]   2> 344322 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[3]} 0 0
   [junit4]   2> 344323 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[4]} 0 0
   [junit4]   2> 344325 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[5]} 0 0
   [junit4]   2> 344327 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[6]} 0 0
   [junit4]   2> 344329 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[7]} 0 0
   [junit4]   2> 344330 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[8]} 0 0
   [junit4]   2> 344332 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[9]} 0 0
   [junit4]   2> 344334 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[10]} 0 0
   [junit4]   2> 344336 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[11]} 0 0
   [junit4]   2> 344337 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[12]} 0 0
   [junit4]   2> 344339 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[13]} 0 0
   [junit4]   2> 344341 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[14]} 0 0
   [junit4]   2> 344343 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[15]} 0 0
   [junit4]   2> 344344 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[16]} 0 0
   [junit4]   2> 344346 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[17]} 0 0
   [junit4]   2> 344348 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[18]} 0 0
   [junit4]   2> 344350 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[19]} 0 0
   [junit4]   2> 344354 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[20]} 0 0
   [junit4]   2> 344356 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[21]} 0 0
   [junit4]   2> 344357 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[22]} 0 0
   [junit4]   2> 344359 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[23]} 0 0
   [junit4]   2> 344361 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[24]} 0 0
   [junit4]   2> 344363 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[25]} 0 0
   [junit4]   2> 344364 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[26]} 0 0
   [junit4]   2> 344366 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[27]} 0 0
   [junit4]   2> 344368 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[28]} 0 0
   [junit4]   2> 344370 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[29]} 0 0
   [junit4]   2> 344375 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[30]} 0 0
   [junit4]   2> 344377 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[31]} 0 0
   [junit4]   2> 344380 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[32]} 0 0
   [junit4]   2> 344383 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[33]} 0 0
   [junit4]   2> 344384 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[34]} 0 0
   [junit4]   2> 344388 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[35]} 0 0
   [junit4]   2> 344390 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[36]} 0 0
   [junit4]   2> 344391 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[37]} 0 0
   [junit4]   2> 344394 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[38]} 0 0
   [junit4]   2> 344396 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[39]} 0 0
   [junit4]   2> 344397 INFO  (qtp2853080-4082) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[40]} 0 0
   [junit4]   2> 344399 INFO  (qtp2853080-4077) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[41]} 0 0
   [junit4]   2> 344401 INFO  (qtp2853080-4078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[42]} 0 0
   [junit4]   2> 344403 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 344406 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3a54d4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17be39a),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3a54d4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17be39a),segFN=segments_2,generation=2}
   [junit4]   2> 344406 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 344409 INFO  (Thread-1303) [    x:collection1] 
o.a.s.h.SnapShooter Creating backup snapshot <not named> at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\
   [junit4]   2> 344409 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@e588d2[collection1] main
   [junit4]   2> 344409 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 344410 INFO  
(searcherExecutor-2145-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e588d2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c43)))}
   [junit4]   2> 344410 INFO  (qtp2853080-4079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 7
   [junit4]   2> 344411 INFO  (qtp2853080-4080) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 344413 INFO  (Thread-1303) [    x:collection1] 
o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\
   [junit4]   2> 345308 INFO  (qtp2853080-4081) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 346291 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testBackupOnCommit
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@7a0761{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18748845
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1511de4
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=1,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=43,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has 
reached 0 - closing IndexWriter
   [junit4]   2> 346292 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 346293 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 346293 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 346293 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\index
 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\index;done=false>>]
   [junit4]   2> 346293 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\index
   [junit4]   2> 346294 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\
 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\;done=false>>]
   [junit4]   2> 346294 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-001\.\collection1\data\
   [junit4]   2> 346294 INFO  
(TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[A8423EFBC2AA7039]) 
[    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1ee7cf9{/solr,null,UNAVAILABLE}
   [junit4]   2> 346296 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting doTestBackup
   [junit4]   2> 346300 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1
   [junit4]   2> 346308 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 346311 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1b704a5{/solr,null,AVAILABLE}
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@13311ab{HTTP/1.1}{127.0.0.1:51679}
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.e.j.s.Server Started @350027ms
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1\data,
 hostContext=/solr, hostPort=51679}
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@e2f2a
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002'
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 346313 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 346314 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\solr.xml
   [junit4]   2> 346319 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.
   [junit4]   2> 346319 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 5303542
   [junit4]   2> 346319 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002]
   [junit4]   2> 346319 WARN  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\lib
 to classpath: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\lib
   [junit4]   2> 346319 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 346320 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 346320 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 346320 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 346320 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 346320 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 346321 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoresLocator Looking for core definitions underneath 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.
   [junit4]   2> 346324 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, 
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1,
 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\,
 dataDir=data\}
   [junit4]   2> 346324 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoresLocator Found core collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\
   [junit4]   2> 346325 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 346325 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1'
   [junit4]   2> 346325 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 346325 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 346326 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 346326 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 346332 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 346334 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 346337 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 346340 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 346341 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 346343 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1'
   [junit4]   2> 346343 INFO  (coreLoadExecutor-2154-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1
   [junit4]   2> 346343 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 346343 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1],
 dataDir=[null]
   [junit4]   2> 346343 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 346343 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 346344 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\index/
   [junit4]   2> 346344 WARN  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\index'
 doesn't exist. Creating new index...
   [junit4]   2> 346344 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\index
   [junit4]   2> 346344 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 346344 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@106807f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e71e9),segFN=segments_1,generation=1}
   [junit4]   2> 346344 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 346345 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 346346 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 346347 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 346348 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 346349 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 346349 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 346350 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 346351 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 346351 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 346351 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 346351 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 346351 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 346352 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@106807f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e71e9),segFN=segments_1,generation=1}
   [junit4]   2> 346352 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 346352 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1571254[collection1] 
main
   [junit4]   2> 346352 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1\conf
   [junit4]   2> 346352 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1\conf}
   [junit4]   2> 346352 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\collection1\conf
   [junit4]   2> 346353 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 346353 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema-replication2.xml:schema.xml
   [junit4]   2> 346353 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 346353 INFO  (coreLoadExecutor-2154-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 346353 INFO  
(searcherExecutor-2155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1571254[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 346819 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 346819 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {deleteByQuery=*:*} 0 0
   [junit4]   2> 346822 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[0]} 0 0
   [junit4]   2> 346824 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[1]} 0 0
   [junit4]   2> 346826 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[2]} 0 0
   [junit4]   2> 346828 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[3]} 0 0
   [junit4]   2> 346830 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[4]} 0 0
   [junit4]   2> 346832 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[5]} 0 0
   [junit4]   2> 346834 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[6]} 0 0
   [junit4]   2> 346837 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[7]} 0 0
   [junit4]   2> 346839 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[8]} 0 0
   [junit4]   2> 346841 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[9]} 0 0
   [junit4]   2> 346843 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[10]} 0 0
   [junit4]   2> 346845 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[11]} 0 0
   [junit4]   2> 346847 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[12]} 0 0
   [junit4]   2> 346848 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[13]} 0 0
   [junit4]   2> 346850 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[14]} 0 0
   [junit4]   2> 346852 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[15]} 0 0
   [junit4]   2> 346854 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[16]} 0 0
   [junit4]   2> 346856 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[17]} 0 0
   [junit4]   2> 346858 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[18]} 0 0
   [junit4]   2> 346860 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[19]} 0 0
   [junit4]   2> 346862 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[20]} 0 0
   [junit4]   2> 346864 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[21]} 0 0
   [junit4]   2> 346866 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[22]} 0 0
   [junit4]   2> 346868 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[23]} 0 0
   [junit4]   2> 346870 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[24]} 0 0
   [junit4]   2> 346871 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[25]} 0 0
   [junit4]   2> 346873 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[26]} 0 0
   [junit4]   2> 346875 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[27]} 0 0
   [junit4]   2> 346877 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[28]} 0 0
   [junit4]   2> 346879 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[29]} 0 0
   [junit4]   2> 346882 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[30]} 0 0
   [junit4]   2> 346885 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[31]} 0 0
   [junit4]   2> 346887 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[32]} 0 0
   [junit4]   2> 346889 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[33]} 0 0
   [junit4]   2> 346891 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[34]} 0 0
   [junit4]   2> 346893 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[35]} 0 0
   [junit4]   2> 346894 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[36]} 0 0
   [junit4]   2> 346896 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[37]} 0 0
   [junit4]   2> 346898 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[38]} 0 0
   [junit4]   2> 346900 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[39]} 0 0
   [junit4]   2> 346902 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[40]} 0 0
   [junit4]   2> 346905 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[41]} 0 0
   [junit4]   2> 346907 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[42]} 0 0
   [junit4]   2> 346909 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[43]} 0 0
   [junit4]   2> 346911 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[44]} 0 0
   [junit4]   2> 346913 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[45]} 0 0
   [junit4]   2> 346915 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[46]} 0 0
   [junit4]   2> 346916 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[47]} 0 0
   [junit4]   2> 346918 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[48]} 0 0
   [junit4]   2> 346920 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[49]} 0 0
   [junit4]   2> 346922 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[50]} 0 0
   [junit4]   2> 346924 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[51]} 0 0
   [junit4]   2> 346926 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[52]} 0 0
   [junit4]   2> 346928 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[53]} 0 0
   [junit4]   2> 346931 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[54]} 0 0
   [junit4]   2> 346933 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[55]} 0 0
   [junit4]   2> 346935 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[56]} 0 0
   [junit4]   2> 346937 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[57]} 0 0
   [junit4]   2> 346938 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[58]} 0 0
   [junit4]   2> 346940 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[59]} 0 0
   [junit4]   2> 346942 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[60]} 0 0
   [junit4]   2> 346944 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[61]} 0 0
   [junit4]   2> 346946 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 346948 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@106807f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e71e9),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@106807f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e71e9),segFN=segments_2,generation=2}
   [junit4]   2> 346949 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 346952 INFO  (Thread-1318) [    x:collection1] 
o.a.s.h.SnapShooter Creating backup snapshot <not named> at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 346952 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1bcd7a[collection1] main
   [junit4]   2> 346953 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 346953 INFO  
(searcherExecutor-2155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1bcd7a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c62)))}
   [junit4]   2> 346953 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 7
   [junit4]   2> 346958 INFO  (Thread-1318) [    x:collection1] 
o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 346959 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={name=phnuls&command=backup} status=0 QTime=2 
   [junit4]   2> 346959 INFO  (Thread-1319) [    x:collection1] 
o.a.s.h.SnapShooter Creating backup snapshot phnuls at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 346961 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 346964 INFO  (Thread-1319) [    x:collection1] 
o.a.s.h.SnapShooter Done creating backup snapshot: phnuls at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 347985 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={name=ijwoeyafuo&command=backup} status=0 QTime=41 
   [junit4]   2> 347986 INFO  (Thread-1320) [    x:collection1] 
o.a.s.h.SnapShooter Creating backup snapshot ijwoeyafuo at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 347987 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 347989 INFO  (Thread-1320) [    x:collection1] 
o.a.s.h.SnapShooter Done creating backup snapshot: ijwoeyafuo at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 348971 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={name=jfhmhovdgw&command=backup} status=0 QTime=1 
   [junit4]   2> 348971 INFO  (Thread-1321) [    x:collection1] 
o.a.s.h.SnapShooter Creating backup snapshot jfhmhovdgw at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 348974 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 348976 INFO  (Thread-1321) [    x:collection1] 
o.a.s.h.SnapShooter Done creating backup snapshot: jfhmhovdgw at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 349959 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={name=gsr&command=backup} status=0 QTime=1 
   [junit4]   2> 349959 INFO  (Thread-1322) [    x:collection1] 
o.a.s.h.SnapShooter Creating backup snapshot gsr at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 349962 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 349963 INFO  (Thread-1322) [    x:collection1] 
o.a.s.h.SnapShooter Done creating backup snapshot: gsr at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 350947 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={name=phnuls&command=deletebackup} status=0 QTime=3 
   [junit4]   2> 350948 INFO  (Thread-1323) [    x:collection1] 
o.a.s.h.SnapShooter Deleting snapshot: phnuls
   [junit4]   2> 350951 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 351934 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={name=ijwoeyafuo&command=deletebackup} status=0 QTime=1 
   [junit4]   2> 351935 INFO  (Thread-1324) [    x:collection1] 
o.a.s.h.SnapShooter Deleting snapshot: ijwoeyafuo
   [junit4]   2> 351937 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=1 
   [junit4]   2> 352136 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 352333 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 352530 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 352727 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 352924 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 353122 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=1 
   [junit4]   2> 353321 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 353520 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 353718 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 353915 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 354112 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 354308 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 354505 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 354702 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 354900 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 355100 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 355315 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 355532 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 355749 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 355966 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 356183 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 356401 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 356619 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 356837 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 357079 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 357302 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 357520 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 357742 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 357962 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 358178 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 358396 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 358613 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 358829 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 359045 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 359263 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 359481 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 359697 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 359915 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 360131 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 360348 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 360565 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 360782 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 360998 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 361215 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 361433 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 361650 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 361868 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 362086 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 362304 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 362521 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 362737 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 362953 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 363171 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 363387 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 363604 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 363821 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 364038 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 364255 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 364472 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 364690 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 364905 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 365123 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 365340 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 365558 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 365775 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 365982 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 366183 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 366385 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 366586 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 366787 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 366988 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 367190 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 367391 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 367592 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 367794 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 367994 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 368196 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 368396 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 368599 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 368800 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 369001 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 369203 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 369403 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 369605 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 369806 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 370007 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 370210 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 370411 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 370612 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 370814 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 371015 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 371217 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 371418 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 371620 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 371820 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 372022 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 372224 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 372425 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 372628 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 372829 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 373031 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 373231 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 373432 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 373634 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 373835 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 374036 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 374237 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 374438 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 374641 INFO  (qtp8681330-4102) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 374843 INFO  (qtp8681330-4103) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 375044 INFO  (qtp8681330-4098) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 375245 INFO  (qtp8681330-4099) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 375446 INFO  (qtp8681330-4100) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication 
params={command=details} status=0 QTime=0 
   [junit4]   2> 375648 INFO  (qtp8681330-4101) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication par

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

state=RUNNABLE, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    2) Thread[id=4096, 
name=qtp8681330-4096-selector-ServerConnectorManager@1f93acc/0, state=RUNNABLE, 
group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    3) Thread[id=4107, name=Thread-1314, state=WAITING, 
group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    4) Thread[id=4100, name=qtp8681330-4100, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    5) Thread[id=4102, name=qtp8681330-4102, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    6) Thread[id=4103, name=qtp8681330-4103, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    7) Thread[id=4111, name=searcherExecutor-2155-thread-1, 
state=WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    8) Thread[id=4105, 
name=org.eclipse.jetty.server.session.HashSessionManager@1b7ec9Timer, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>    9) Thread[id=4113, name=Scheduler-11037615, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>   10) Thread[id=4099, name=qtp8681330-4099, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>   11) Thread[id=4073, 
name=TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039], 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>   12) Thread[id=4098, name=qtp8681330-4098, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>   13) Thread[id=4101, name=qtp8681330-4101, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2>   14) Thread[id=4104, name=Scheduler-17503128, 
state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
   [junit4]   2> 7597484 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending doTestBackup
   [junit4]   2> 7597493 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.e.j.s.ServerConnector Stopped ServerConnector@13311ab{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 7597493 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=5303542
   [junit4]   2> 7597494 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@678bca
   [junit4]   2> 7597494 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=1,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=62,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
   [junit4]   2> 7597494 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 7597494 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 
0 - closing IndexWriter
   [junit4]   2> 7597494 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 7597494 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 7597495 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 
directories currently being tracked
   [junit4]   2> 7597495 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\index
 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\index;done=false>>]
   [junit4]   2> 7597495 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\index
   [junit4]   2> 7597495 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\;done=false>>]
   [junit4]   2> 7597495 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001\solr-instance-002\.\collection1\data\
   [junit4]   2> 7597496 INFO  
(TEST-TestReplicationHandlerBackup.doTestBackup-seed#[A8423EFBC2AA7039]) [    
x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1b704a5{/solr,null,UNAVAILABLE}
   [junit4]   2> 7597497 INFO  
(SUITE-TestReplicationHandlerBackup-seed#[A8423EFBC2AA7039]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_A8423EFBC2AA7039-001
   [junit4]   2> Nov 28, 2015 6:07:03 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: All leaked threads terminated.
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): 
{name=Lucene50(blocksize=128), id=Lucene50(blocksize=128)}, docValues:{}, 
sim=ClassicSimilarity, locale=zh_TW, timezone=America/Boise
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_66 
(32-bit)/cpus=3,threads=1,free=260771232,total=297652224
   [junit4]   2> NOTE: All tests run in this JVM: [TestLRUStatsCache, 
TestCSVLoader, HdfsCollectionsAPIDistributedZkTest, TestFunctionQuery, 
SimplePostToolTest, TestCopyFieldCollectionResource, 
TestPostingsSolrHighlighter, SystemInfoHandlerTest, SolrIndexConfigTest, 
TestCollectionAPI, JsonLoaderTest, TestHdfsUpdateLog, TestSolrConfigHandler, 
CurrencyFieldXmlFileTest, SpatialRPTFieldTypeTest, MultiThreadedOCPTest, 
TestRestoreCore, ExplicitHLLTest, DistributedSpellCheckComponentTest, 
TestSolrConfigHandlerCloud, DOMUtilTest, SimpleMLTQParserTest, 
TestSortingResponseWriter, DirectSolrSpellCheckerTest, 
TestImplicitCoreProperties, TestExpandComponent, XmlUpdateRequestHandlerTest, 
TestJmxMonitoredMap, MoreLikeThisHandlerTest, TestGroupingSearch, 
TestStressUserVersions, RequiredFieldsTest, ZkControllerTest, 
TestSchemaNameResource, RestartWhileUpdatingTest, TestCryptoKeys, 
TestWordDelimiterFilterFactory, DateRangeFieldTest, CdcrVersionReplicationTest, 
TestDocBasedVersionConstraints, HLLUtilTest, SpatialFilterTest, 
TestSolrDeletionPolicy1, TestReplicationHandlerBackup]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandlerBackup -Dtests.seed=A8423EFBC2AA7039 
-Dtests.slow=true -Dtests.locale=zh_TW -Dtests.timezone=America/Boise 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | TestReplicationHandlerBackup (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 
7200000 msec).
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A8423EFBC2AA7039]:0)
   [junit4] Completed [467/562] on J0 in 7253.70s, 2 tests, 2 errors <<< 
FAILURES!

[...truncated 317 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:775: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:719: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:59: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:233: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:516:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1432:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:987:
 There were test failures: 562 suites (8 ignored), 2228 tests, 1 suite-level 
error, 1 error, 116 ignored (78 assumptions) [seed: A8423EFBC2AA7039]

Total time: 161 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



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