Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id D2A7817D65 for ; Wed, 26 Aug 2015 11:55:54 +0000 (UTC) Received: (qmail 13284 invoked by uid 500); 26 Aug 2015 11:55:51 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 13220 invoked by uid 500); 26 Aug 2015 11:55:51 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 13210 invoked by uid 99); 26 Aug 2015 11:55:51 -0000 Received: from Unknown (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 26 Aug 2015 11:55:51 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id BEC5DEE2E8 for ; Wed, 26 Aug 2015 11:55:50 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 0.001 X-Spam-Level: X-Spam-Status: No, score=0.001 tagged_above=-999 required=6.31 tests=[FSL_HELO_NON_FQDN_1=0.001, SPF_PASS=-0.001, URIBL_BLOCKED=0.001] autolearn=disabled Received: from mx1-us-west.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id vplDNAMu4XlE for ; Wed, 26 Aug 2015 11:55:44 +0000 (UTC) Received: from serv1 (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-us-west.apache.org (ASF Mail Server at mx1-us-west.apache.org) with ESMTPS id 3761320382 for ; Wed, 26 Aug 2015 11:55:43 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1ZUZIl-0004NR-Bf for dev@lucene.apache.org; Wed, 26 Aug 2015 11:55:35 +0000 Date: Wed, 26 Aug 2015 11:55:22 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1747339628.17.1440590135359.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0_60) - Build # 5202 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_16_328562438.1440590135304" X-Jenkins-Job: Lucene-Solr-trunk-Windows X-Jenkins-Result: FAILURE ------=_Part_16_328562438.1440590135304 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5202/ Java: 64bit/jdk1.8.0_60 -XX:-UseCompressedOops -XX:+UseParallelGC 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. =09at __randomizedtesting.SeedInfo.seed([B466C333BCD6B09]:0) FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationH= andlerBackup Error Message: Suite timeout exceeded (>=3D 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>=3D 7200000 msec). =09at __randomizedtesting.SeedInfo.seed([B466C333BCD6B09]:0) Build Log: [...truncated 11052 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandlerBackup [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplic= ationHandlerBackup_B466C333BCD6B09-001\init-core-data-001 [junit4] 2> 2667536 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.SolrTestCaseJ4 ###Starting t= estBackupOnCommit [junit4] 2> 2667537 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.SolrTestCaseJ4 Writing core.= properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBack= up_B466C333BCD6B09-001\solr-instance-001\collection1 [junit4] 2> 2667546 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.e.j.s.Server jetty-9.2.13.v20150= 730 [junit4] 2> 2667548 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.e.j.s.h.ContextHandler Started o= .e.j.s.ServletContextHandler@52f8bdf8{/solr,null,AVAILABLE} [junit4] 2> 2667550 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.e.j.s.ServerConnector Started Se= rverConnector@39be83e5{HTTP/1.1}{127.0.0.1:57610} [junit4] 2> 2667550 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.e.j.s.Server Started @2678201ms [junit4] 2> 2667550 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty = properties: {solr.data.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-tr= unk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationH= andlerBackup_B466C333BCD6B09-001\solr-instance-001\collection1\data, hostCo= ntext=3D/solr, hostPort=3D57610} [junit4] 2> 2667551 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.s.SolrDispatchFilter SolrDis= patchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2667551 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrResourceLoader new Sol= rResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr= -trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicati= onHandlerBackup_B466C333BCD6B09-001\solr-instance-001\' [junit4] 2> 2667569 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrXmlConfig Loading cont= ainer configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-= Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandl= erBackup_B466C333BCD6B09-001\solr-instance-001\solr.xml [junit4] 2> 2667577 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Config-define= d core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wi= ndows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler= Backup_B466C333BCD6B09-001\solr-instance-001\. [junit4] 2> 2667577 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer New CoreCont= ainer 14928761 [junit4] 2> 2667577 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer Loading core= s into CoreContainer [instanceDir=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRepli= cationHandlerBackup_B466C333BCD6B09-001\solr-instance-001\] [junit4] 2> 2667577 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer loading shar= ed library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466= C333BCD6B09-001\solr-instance-001\lib [junit4] 2> 2667577 WARN (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrResourceLoader Can't f= ind (or read) directory to add to classloader: lib (resolved as: C:\Users\J= enkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J= 0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-i= nstance-001\lib). [junit4] 2> 2667584 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.h.c.HttpShardHandlerFactory = created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConn= ectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize= : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false= ,useRetries : false, [junit4] 2> 2667586 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.u.UpdateShardHandler Creatin= g UpdateShardHandler HTTP client with params: socketTimeout=3D340000&connTi= meout=3D45000&retry=3Dtrue [junit4] 2> 2667587 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.l.LogWatcher SLF4J impl is o= rg.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2667587 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.l.LogWatcher Registering Log= Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2667587 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer Security con= f doesn't exist. Skipping setup for authorization module. [junit4] 2> 2667587 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer No authentic= ation plugin used. [junit4] 2> 2667588 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Looking for c= ore definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHan= dlerBackup_B466C333BCD6B09-001\solr-instance-001\. [junit4] 2> 2667590 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrCore Created CoreDescr= iptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, sch= ema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetprop= s.json, instanceDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerB= ackup_B466C333BCD6B09-001\solr-instance-001\.\collection1, absoluteInstDir= =3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\sol= r-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B= 09-001\solr-instance-001\.\collection1\, dataDir=3Ddata\} [junit4] 2> 2667590 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Found core co= llection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B46= 6C333BCD6B09-001\solr-instance-001\.\collection1\ [junit4] 2> 2667591 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Found 1 core = definitions [junit4] 2> 2667592 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\Jenk= insSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\t= emp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-inst= ance-001\.\collection1\' [junit4] 2> 2667593 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.s.SolrDispatchFilter user.di= r=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\so= lr-core\test\J0 [junit4] 2> 2667593 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.s.SolrDispatchFilter SolrDis= patchFilter.init() done [junit4] 2> 2667611 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2667614 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2667632 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 2667632 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lu= cene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.Test= ReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-001\.\collection= 1\conf\schema.xml [junit4] 2> 2667635 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 2667637 INFO (coreLoadExecutor-7752-thread-1) [ ] o.a.= s.s.IndexSchema unique key field: id [junit4] 2> 2667639 INFO (coreLoadExecutor-7752-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_B466C333BCD6B09-001= \solr-instance-001\.\collection1\conf' [junit4] 2> 2667639 INFO (coreLoadExecutor-7752-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_B466= C333BCD6B09-001\solr-instance-001\.\collection1\ [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\User= s\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\tes= t\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\sol= r-instance-001\.\collection1\], dataDir=3D[null] [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] 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_B466C333BCD6B09-001\solr-= instance-001\.\collection1\data\ [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore New index directory detected: old=3Dnull new=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-00= 1\solr-instance-001\.\collection1\data\index/ [junit4] 2> 2667640 WARN (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\Jenk= insSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\t= emp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-inst= ance-001\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] 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_B466C333BCD6B09-001\solr-= instance-001\.\collection1\data\index [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apa= che.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D20= , maxMergeAtOnceExplicit=3D48, maxMergedSegmentMB=3D5.9384765625, floorSegm= entMB=3D2.0126953125, forceMergeDeletesPctAllowed=3D9.374951489217743, segm= entsPerTier=3D45.0, maxCFSSegmentSizeMB=3D2.162109375, noCFSRatio=3D0.22244= 954677596518 [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@10ef7c15= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@7c62de0),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 2667640 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 2667641 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [ x:collection1] o.a.s.c.SolrCore Looking for old index director= ies to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Luce= ne-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRe= plicationHandlerBackup_B466C333BCD6B09-001\solr-instance-001\.\collection1\= data\ [junit4] 2> 2667643 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default= , creating implicit default [junit4] 2> 2667646 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2667646 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2667647 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2667648 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2667649 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] 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/loggi= ng,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.s= earch.stats.LocalStatsCache [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apa= che.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D45= , maxMergeAtOnceExplicit=3D17, maxMergedSegmentMB=3D95.9736328125, floorSeg= mentMB=3D1.8798828125, forceMergeDeletesPctAllowed=3D18.81393747742557, seg= mentsPerTier=3D11.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.0 [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@10ef7c15= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@7c62de0),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 2667650 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4fa5de4d[collection1] m= ain [junit4] 2> 2667651 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to u= se dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\buil= d\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333= BCD6B09-001\solr-instance-001\.\collection1\conf [junit4] 2> 2667651 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {stora= geDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\buil= d\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333= BCD6B09-001\solr-instance-001\.\collection1\conf} [junit4] 2> 2667651 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using fi= le:dir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\bui= ld\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C33= 3BCD6B09-001\solr-instance-001\.\collection1\conf [junit4] 2> 2667651 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2667651 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.h.ReplicationHandler Replication enabled for following confi= g files: schema-replication2.xml:schema.xml [junit4] 2> 2667651 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2667652 INFO (searcherExecutor-7753-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered= new searcher Searcher@4fa5de4d[collection1] main{ExitableDirectoryReader(U= ninvertingDirectoryReader())} [junit4] 2> 2667652 INFO (coreLoadExecutor-7752-thread-1) [ x:coll= ection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2668025 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 2668025 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {deleteByQuery=3D*:*} 0 0 [junit4] 2> 2668028 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[0]} 0 1 [junit4] 2> 2668031 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[1]} 0 0 [junit4] 2> 2668033 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[2]} 0 0 [junit4] 2> 2668036 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[3]} 0 0 [junit4] 2> 2668037 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[4]} 0 0 [junit4] 2> 2668039 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[5]} 0 0 [junit4] 2> 2668041 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[6]} 0 0 [junit4] 2> 2668042 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[7]} 0 0 [junit4] 2> 2668044 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[8]} 0 0 [junit4] 2> 2668046 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[9]} 0 0 [junit4] 2> 2668047 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[10]} 0 0 [junit4] 2> 2668049 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[11]} 0 0 [junit4] 2> 2668051 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[12]} 0 0 [junit4] 2> 2668052 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[13]} 0 0 [junit4] 2> 2668054 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[14]} 0 0 [junit4] 2> 2668056 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[15]} 0 0 [junit4] 2> 2668058 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[16]} 0 0 [junit4] 2> 2668059 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[17]} 0 0 [junit4] 2> 2668061 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[18]} 0 0 [junit4] 2> 2668063 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[19]} 0 0 [junit4] 2> 2668065 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[20]} 0 0 [junit4] 2> 2668067 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[21]} 0 0 [junit4] 2> 2668068 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[22]} 0 0 [junit4] 2> 2668070 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[23]} 0 0 [junit4] 2> 2668072 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[24]} 0 0 [junit4] 2> 2668073 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[25]} 0 0 [junit4] 2> 2668075 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[26]} 0 0 [junit4] 2> 2668077 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[27]} 0 0 [junit4] 2> 2668079 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[28]} 0 0 [junit4] 2> 2668080 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[29]} 0 0 [junit4] 2> 2668082 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[30]} 0 0 [junit4] 2> 2668084 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[31]} 0 0 [junit4] 2> 2668085 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[32]} 0 0 [junit4] 2> 2668087 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[33]} 0 0 [junit4] 2> 2668089 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[34]} 0 0 [junit4] 2> 2668090 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[35]} 0 0 [junit4] 2> 2668092 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[36]} 0 0 [junit4] 2> 2668094 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[37]} 0 0 [junit4] 2> 2668095 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[38]} 0 0 [junit4] 2> 2668097 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[39]} 0 0 [junit4] 2> 2668099 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[40]} 0 0 [junit4] 2> 2668100 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[41]} 0 0 [junit4] 2> 2668102 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[42]} 0 0 [junit4] 2> 2668104 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[43]} 0 0 [junit4] 2> 2668105 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[44]} 0 0 [junit4] 2> 2668107 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[45]} 0 0 [junit4] 2> 2668110 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[46]} 0 0 [junit4] 2> 2668111 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[47]} 0 0 [junit4] 2> 2668113 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[48]} 0 0 [junit4] 2> 2668115 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[49]} 0 0 [junit4] 2> 2668117 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[50]} 0 0 [junit4] 2> 2668120 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[51]} 0 0 [junit4] 2> 2668121 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[52]} 0 0 [junit4] 2> 2668123 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[53]} 0 0 [junit4] 2> 2668125 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[54]} 0 0 [junit4] 2> 2668126 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[55]} 0 0 [junit4] 2> 2668128 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[56]} 0 0 [junit4] 2> 2668131 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[57]} 0 0 [junit4] 2> 2668132 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[58]} 0 0 [junit4] 2> 2668134 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[59]} 0 0 [junit4] 2> 2668136 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[60]} 0 0 [junit4] 2> 2668137 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[61]} 0 0 [junit4] 2> 2668139 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[62]} 0 0 [junit4] 2> 2668141 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[63]} 0 0 [junit4] 2> 2668142 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[64]} 0 0 [junit4] 2> 2668144 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[65]} 0 0 [junit4] 2> 2668146 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[66]} 0 0 [junit4] 2> 2668147 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[67]} 0 0 [junit4] 2> 2668149 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[68]} 0 0 [junit4] 2> 2668151 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[69]} 0 0 [junit4] 2> 2668152 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[70]} 0 0 [junit4] 2> 2668154 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[71]} 0 0 [junit4] 2> 2668156 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[72]} 0 0 [junit4] 2> 2668157 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[73]} 0 0 [junit4] 2> 2668159 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[74]} 0 0 [junit4] 2> 2668161 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[75]} 0 0 [junit4] 2> 2668162 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[76]} 0 0 [junit4] 2> 2668164 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[77]} 0 0 [junit4] 2> 2668166 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[78]} 0 0 [junit4] 2> 2668167 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[79]} 0 0 [junit4] 2> 2668170 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[80]} 0 0 [junit4] 2> 2668172 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[81]} 0 0 [junit4] 2> 2668173 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[82]} 0 0 [junit4] 2> 2668175 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[83]} 0 0 [junit4] 2> 2668177 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[84]} 0 0 [junit4] 2> 2668178 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[85]} 0 0 [junit4] 2> 2668180 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[86]} 0 0 [junit4] 2> 2668182 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[87]} 0 0 [junit4] 2> 2668183 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[88]} 0 0 [junit4] 2> 2668185 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[89]} 0 0 [junit4] 2> 2668187 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[90]} 0 0 [junit4] 2> 2668188 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[91]} 0 0 [junit4] 2> 2668190 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[92]} 0 0 [junit4] 2> 2668192 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[93]} 0 0 [junit4] 2> 2668193 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[94]} 0 0 [junit4] 2> 2668195 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[95]} 0 0 [junit4] 2> 2668197 INFO (qtp1585873013-17699) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[96]} 0 0 [junit4] 2> 2668199 INFO (qtp1585873013-17700) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[97]} 0 0 [junit4] 2> 2668201 INFO (qtp1585873013-17701) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[98]} 0 0 [junit4] 2> 2668203 INFO (qtp1585873013-17702) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{wt=3Djavabin&version=3D2} {add=3D[99]} 0 0 [junit4] 2> 2668204 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.UpdateHandler start commit{,optimize=3Dfalse,openSearcher=3Dtrue,wait= Searcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Df= alse} [junit4] 2> 2668207 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@10ef7c15= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@7c62de0),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@10ef7c15= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@7c62de0),s= egFN=3Dsegments_2,generation=3D2} [junit4] 2> 2668207 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.c.SolrCore newest commit generation =3D 2 [junit4] 2> 2668209 INFO (Thread-6659) [ x:collection1] o.a.s.h.Sn= apShooter Creating backup snapshot at C:\Users\JenkinsSlave\wor= kspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.han= dler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-001\.\c= ollection1\data\ [junit4] 2> 2668209 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.s.SolrIndexSearcher Opening Searcher@57cf52d7[collection1] main [junit4] 2> 2668209 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.UpdateHandler end_commit_flush [junit4] 2> 2668209 INFO (searcherExecutor-7753-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered= new searcher Searcher@57cf52d7[collection1] main{ExitableDirectoryReader(U= ninvertingDirectoryReader(Uninverting(_0(6.0.0):c100)))} [junit4] 2> 2668209 INFO (qtp1585873013-17703) [ x:collection1] o.= a.s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update para= ms=3D{waitSearcher=3Dtrue&commit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&ver= sion=3D2} {commit=3D} 0 5 [junit4] 2> 2668212 INFO (Thread-6659) [ x:collection1] o.a.s.h.Sn= apShooter Done creating backup snapshot: at C:\Users\JenkinsSla= ve\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\so= lr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-0= 01\.\collection1\data\ [junit4] 2> 2668216 INFO (qtp1585873013-17698) [ x:collection1] o.= a.s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{= command=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2669111 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.SolrTestCaseJ4 ###Ending tes= tBackupOnCommit [junit4] 2> 2669112 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.e.j.s.ServerConnector Stopped Se= rverConnector@39be83e5{HTTP/1.1}{127.0.0.1:0} [junit4] 2> 2669112 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer Shutting dow= n CoreContainer instance=3D14928761 [junit4] 2> 2669112 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.SolrCore [col= lection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6d86901e [junit4] 2> 2669112 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.u.UpdateHandler= closing DirectUpdateHandler2{commits=3D1,autocommits=3D0,soft autocommits= =3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D= 0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D100,cumul= ative_deletesById=3D0,cumulative_deletesByQuery=3D1,cumulative_errors=3D0} [junit4] 2> 2669114 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.u.SolrCoreState= Closing SolrCoreState [junit4] 2> 2669114 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.u.DefaultSolrCo= reState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 2669114 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.u.DefaultSolrCo= reState closing IndexWriter with IndexWriterCloser [junit4] 2> 2669115 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.SolrCore [col= lection1] Closing main searcher on request. [junit4] 2> 2669115 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirect= oryFactory Closing MockDirectoryFactory - 2 directories currently being tra= cked [junit4] 2> 2669115 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirect= oryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-tru= nk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHa= ndlerBackup_B466C333BCD6B09-001\solr-instance-001\.\collection1\data\index = [CachedDir<>] [junit4] 2> 2669115 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirect= oryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplication= HandlerBackup_B466C333BCD6B09-001\solr-instance-001\.\collection1\data\inde= x [junit4] 2> 2669116 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirect= oryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-tru= nk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHa= ndlerBackup_B466C333BCD6B09-001\solr-instance-001\.\collection1\data\ [Cach= edDir<>] [junit4] 2> 2669116 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirect= oryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplication= HandlerBackup_B466C333BCD6B09-001\solr-instance-001\.\collection1\data\ [junit4] 2> 2669116 INFO (TEST-TestReplicationHandlerBackup.testBacku= pOnCommit-seed#[B466C333BCD6B09]) [ x:collection1] o.e.j.s.h.ContextHand= ler Stopped o.e.j.s.ServletContextHandler@52f8bdf8{/solr,null,UNAVAILABLE} [junit4] 2> 2669120 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestB= ackup [junit4] 2> 2669137 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.SolrTestCaseJ4 Writing core.proper= ties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B46= 6C333BCD6B09-001\solr-instance-002\collection1 [junit4] 2> 2669148 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 2669150 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s= .ServletContextHandler@1afeddfb{/solr,null,AVAILABLE} [junit4] 2> 2669152 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.e.j.s.ServerConnector Started ServerCo= nnector@4d2321f{HTTP/1.1}{127.0.0.1:57614} [junit4] 2> 2669152 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.e.j.s.Server Started @2680027ms [junit4] 2> 2669152 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty proper= ties: {solr.data.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wi= ndows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler= Backup_B466C333BCD6B09-001\solr-instance-002\collection1\data, hostContext= =3D/solr, hostPort=3D57614} [junit4] 2> 2669152 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchF= ilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2669153 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrResourceLoader new SolrResou= rceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHand= lerBackup_B466C333BCD6B09-001\solr-instance-002\' [junit4] 2> 2669172 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrXmlConfig Loading container = configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBack= up_B466C333BCD6B09-001\solr-instance-002\solr.xml [junit4] 2> 2669178 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] 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= _B466C333BCD6B09-001\solr-instance-002\. [junit4] 2> 2669178 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer New CoreContainer = 1768052104 [junit4] 2> 2669178 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer Loading cores into= CoreContainer [instanceDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplication= HandlerBackup_B466C333BCD6B09-001\solr-instance-002\] [junit4] 2> 2669179 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer loading shared lib= rary: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\= solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BC= D6B09-001\solr-instance-002\lib [junit4] 2> 2669179 WARN (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrResourceLoader Can't find (o= r read) directory to add to classloader: lib (resolved as: C:\Users\Jenkins= Slave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp= \solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instanc= e-002\lib). [junit4] 2> 2669186 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.h.c.HttpShardHandlerFactory create= d with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnection= sPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 214= 7483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRe= tries : false, [junit4] 2> 2669188 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.u.UpdateShardHandler Creating Upda= teShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dtrue [junit4] 2> 2669189 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf= 4j.impl.Log4jLoggerFactory [junit4] 2> 2669189 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.l.LogWatcher Registering Log Liste= ner [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2669189 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer Security conf does= n't exist. Skipping setup for authorization module. [junit4] 2> 2669189 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoreContainer No authentication = plugin used. [junit4] 2> 2669190 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Looking for core de= finitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wind= ows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBa= ckup_B466C333BCD6B09-001\solr-instance-002\. [junit4] 2> 2669191 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.SolrCore Created CoreDescriptor:= {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schema=3D= schema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetprops.json= , instanceDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_= B466C333BCD6B09-001\solr-instance-002\.\collection1, absoluteInstDir=3DC:\U= sers\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\= test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\= solr-instance-002\.\collection1\, dataDir=3Ddata\} [junit4] 2> 2669192 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Found core collecti= on1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build= \solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333B= CD6B09-001\solr-instance-002\.\collection1\ [junit4] 2> 2669193 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.c.CoresLocator Found 1 core defini= tions [junit4] 2> 2669193 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\Jenk= insSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\t= emp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-inst= ance-002\.\collection1\' [junit4] 2> 2669194 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.s.SolrDispatchFilter user.dir=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0 [junit4] 2> 2669194 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchF= ilter.init() done [junit4] 2> 2669213 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2669215 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2669219 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 2669220 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lu= cene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.Test= ReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\collection= 1\conf\schema.xml [junit4] 2> 2669222 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 2669225 INFO (coreLoadExecutor-7762-thread-1) [ ] o.a.= s.s.IndexSchema unique key field: id [junit4] 2> 2669227 INFO (coreLoadExecutor-7762-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_B466C333BCD6B09-001= \solr-instance-002\.\collection1\conf' [junit4] 2> 2669228 INFO (coreLoadExecutor-7762-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_B466= C333BCD6B09-001\solr-instance-002\.\collection1\ [junit4] 2> 2669228 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2669228 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\User= s\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\tes= t\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\sol= r-instance-002\.\collection1\], dataDir=3D[null] [junit4] 2> 2669228 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 2669228 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] 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_B466C333BCD6B09-001\solr-= instance-002\.\collection1\data\ [junit4] 2> 2669228 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore New index directory detected: old=3Dnull new=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-00= 1\solr-instance-002\.\collection1\data\index/ [junit4] 2> 2669229 WARN (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\Jenk= insSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\t= emp\solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-inst= ance-002\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 2669229 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] 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_B466C333BCD6B09-001\solr-= instance-002\.\collection1\data\index [junit4] 2> 2669229 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apa= che.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D20= , maxMergeAtOnceExplicit=3D48, maxMergedSegmentMB=3D5.9384765625, floorSegm= entMB=3D2.0126953125, forceMergeDeletesPctAllowed=3D9.374951489217743, segm= entsPerTier=3D45.0, maxCFSSegmentSizeMB=3D2.162109375, noCFSRatio=3D0.22244= 954677596518 [junit4] 2> 2669229 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@140cb177= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@655f4edd),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 2669229 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 2669230 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [ x:collection1] o.a.s.c.SolrCore Looking for old index director= ies to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Luce= ne-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRe= plicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\collection1\= data\ [junit4] 2> 2669230 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default= , creating implicit default [junit4] 2> 2669234 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2669235 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2669236 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2669237 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 2669238 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] 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/loggi= ng,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 2669239 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.s= earch.stats.LocalStatsCache [junit4] 2> 2669239 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 2669239 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 2669239 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apa= che.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D45= , maxMergeAtOnceExplicit=3D17, maxMergedSegmentMB=3D95.9736328125, floorSeg= mentMB=3D1.8798828125, forceMergeDeletesPctAllowed=3D18.81393747742557, seg= mentsPerTier=3D11.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.0 [junit4] 2> 2669240 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@140cb177= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@655f4edd),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 2669240 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 2669240 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.s.SolrIndexSearcher Opening Searcher@75dd798d[collection1] m= ain [junit4] 2> 2669241 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to u= se dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\buil= d\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333= BCD6B09-001\solr-instance-002\.\collection1\conf [junit4] 2> 2669241 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {stora= geDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\buil= d\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C333= BCD6B09-001\solr-instance-002\.\collection1\conf} [junit4] 2> 2669241 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using fi= le:dir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\bui= ld\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerBackup_B466C33= 3BCD6B09-001\solr-instance-002\.\collection1\conf [junit4] 2> 2669241 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2669242 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.h.ReplicationHandler Replication enabled for following confi= g files: schema-replication2.xml:schema.xml [junit4] 2> 2669242 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2669242 INFO (searcherExecutor-7763-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered= new searcher Searcher@75dd798d[collection1] main{ExitableDirectoryReader(U= ninvertingDirectoryReader())} [junit4] 2> 2669242 INFO (coreLoadExecutor-7762-thread-1) [ x:coll= ection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2669677 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 2669677 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {deleteByQuery=3D*:*} 0 0 [junit4] 2> 2669681 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[0]} 0 1 [junit4] 2> 2669683 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[1]} 0 0 [junit4] 2> 2669687 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[2]} 0 0 [junit4] 2> 2669688 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[3]} 0 0 [junit4] 2> 2669690 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[4]} 0 0 [junit4] 2> 2669692 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[5]} 0 0 [junit4] 2> 2669694 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[6]} 0 0 [junit4] 2> 2669696 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[7]} 0 0 [junit4] 2> 2669698 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[8]} 0 0 [junit4] 2> 2669699 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[9]} 0 0 [junit4] 2> 2669701 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[10]} 0 0 [junit4] 2> 2669703 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[11]} 0 0 [junit4] 2> 2669705 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[12]} 0 0 [junit4] 2> 2669707 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[13]} 0 0 [junit4] 2> 2669709 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[14]} 0 0 [junit4] 2> 2669711 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[15]} 0 0 [junit4] 2> 2669712 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[16]} 0 0 [junit4] 2> 2669714 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[17]} 0 0 [junit4] 2> 2669716 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[18]} 0 0 [junit4] 2> 2669718 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[19]} 0 0 [junit4] 2> 2669720 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[20]} 0 0 [junit4] 2> 2669722 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[21]} 0 0 [junit4] 2> 2669723 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[22]} 0 0 [junit4] 2> 2669725 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[23]} 0 0 [junit4] 2> 2669727 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[24]} 0 0 [junit4] 2> 2669729 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[25]} 0 0 [junit4] 2> 2669731 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[26]} 0 0 [junit4] 2> 2669733 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[27]} 0 0 [junit4] 2> 2669735 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[28]} 0 0 [junit4] 2> 2669736 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[29]} 0 0 [junit4] 2> 2669738 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[30]} 0 0 [junit4] 2> 2669740 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[31]} 0 0 [junit4] 2> 2669742 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[32]} 0 0 [junit4] 2> 2669744 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{wt=3Djavabin&version=3D2} {add=3D[33]} 0 0 [junit4] 2> 2669746 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.UpdateHandler start commit{,optimize=3Dfalse,openSearcher=3Dtrue,waitS= earcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfa= lse} [junit4] 2> 2669748 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@140cb177= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@655f4edd),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@140cb177= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@655f4edd),= segFN=3Dsegments_2,generation=3D2} [junit4] 2> 2669749 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.SolrCore newest commit generation =3D 2 [junit4] 2> 2669751 INFO (Thread-6674) [ x:collection1] o.a.s.h.Sn= apShooter Creating backup snapshot at C:\Users\JenkinsSlave\wor= kspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.han= dler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\c= ollection1\data\ [junit4] 2> 2669751 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.s.SolrIndexSearcher Opening Searcher@471a82d1[collection1] main [junit4] 2> 2669751 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.UpdateHandler end_commit_flush [junit4] 2> 2669751 INFO (searcherExecutor-7763-thread-1-processing-x= :collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered= new searcher Searcher@471a82d1[collection1] main{ExitableDirectoryReader(U= ninvertingDirectoryReader(Uninverting(_0(6.0.0):c34)))} [junit4] 2> 2669751 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.u.p.LogUpdateProcessor [collection1] webapp=3D/solr path=3D/update param= s=3D{waitSearcher=3Dtrue&commit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&vers= ion=3D2} {commit=3D} 0 6 [junit4] 2> 2669755 INFO (Thread-6674) [ x:collection1] o.a.s.h.Sn= apShooter Done creating backup snapshot: at C:\Users\JenkinsSla= ve\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\so= lr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-0= 02\.\collection1\data\ [junit4] 2> 2669756 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{n= ame=3Dsbs&command=3Dbackup} status=3D0 QTime=3D2=20 [junit4] 2> 2669756 INFO (Thread-6675) [ x:collection1] o.a.s.h.Sn= apShooter Creating backup snapshot sbs at C:\Users\JenkinsSlave\workspace\L= ucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.Tes= tReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\collectio= n1\data\ [junit4] 2> 2669759 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2669762 INFO (Thread-6675) [ x:collection1] o.a.s.h.Sn= apShooter Done creating backup snapshot: sbs at C:\Users\JenkinsSlave\works= pace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handl= er.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\col= lection1\data\ [junit4] 2> 2670708 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{n= ame=3Dacmsxsm&command=3Dbackup} status=3D0 QTime=3D1=20 [junit4] 2> 2670708 INFO (Thread-6676) [ x:collection1] o.a.s.h.Sn= apShooter Creating backup snapshot acmsxsm at C:\Users\JenkinsSlave\workspa= ce\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler= .TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\colle= ction1\data\ [junit4] 2> 2670712 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2670715 INFO (Thread-6676) [ x:collection1] o.a.s.h.Sn= apShooter Done creating backup snapshot: acmsxsm at C:\Users\JenkinsSlave\w= orkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.h= andler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.= \collection1\data\ [junit4] 2> 2671663 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{n= ame=3Dmbaunmr&command=3Dbackup} status=3D0 QTime=3D4=20 [junit4] 2> 2671664 INFO (Thread-6677) [ x:collection1] o.a.s.h.Sn= apShooter Creating backup snapshot mbaunmr at C:\Users\JenkinsSlave\workspa= ce\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler= .TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\colle= ction1\data\ [junit4] 2> 2671666 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2671667 INFO (Thread-6677) [ x:collection1] o.a.s.h.Sn= apShooter Done creating backup snapshot: mbaunmr at C:\Users\JenkinsSlave\w= orkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.h= andler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.= \collection1\data\ [junit4] 2> 2672614 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{n= ame=3Dbvgzrogi&command=3Dbackup} status=3D0 QTime=3D1=20 [junit4] 2> 2672614 INFO (Thread-6678) [ x:collection1] o.a.s.h.Sn= apShooter Creating backup snapshot bvgzrogi at C:\Users\JenkinsSlave\worksp= ace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handle= r.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\.\coll= ection1\data\ [junit4] 2> 2672616 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2672617 INFO (Thread-6678) [ x:collection1] o.a.s.h.Sn= apShooter Done creating backup snapshot: bvgzrogi at C:\Users\JenkinsSlave\= workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.= handler.TestReplicationHandlerBackup_B466C333BCD6B09-001\solr-instance-002\= .\collection1\data\ [junit4] 2> 2673566 INFO (Thread-6679) [ x:collection1] o.a.s.h.Sn= apShooter Deleting snapshot: sbs [junit4] 2> 2673566 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{n= ame=3Dsbs&command=3Ddeletebackup} status=3D0 QTime=3D3=20 [junit4] 2> 2673570 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2674517 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{n= ame=3Dacmsxsm&command=3Ddeletebackup} status=3D0 QTime=3D0=20 [junit4] 2> 2674517 INFO (Thread-6680) [ x:collection1] o.a.s.h.Sn= apShooter Deleting snapshot: acmsxsm [junit4] 2> 2674518 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2674707 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2674897 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2675086 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2675276 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2675466 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2675657 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2675846 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2676036 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2676227 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2676417 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2676607 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2676797 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2676986 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2677175 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2677366 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2677555 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2677746 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2677935 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2678125 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2678324 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2678534 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2678743 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2678975 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2679184 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2679394 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2679602 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2679810 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2680020 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2680229 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2680439 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2680648 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2680857 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2681067 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2681275 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2681485 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2681694 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2681903 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2682113 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2682322 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2682532 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2682742 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2682951 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2683160 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2683369 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2683579 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2683788 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2683997 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2684206 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2684416 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2684626 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2684835 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2685044 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2685252 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2685461 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2685671 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2685880 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2686089 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2686297 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2686505 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2686714 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2686926 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2687135 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2687343 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2687552 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2687761 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2687971 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2688181 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2688389 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2688598 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2688827 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2689056 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2689285 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2689514 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2689744 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2689974 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2690204 INFO (qtp395168789-17722) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2690433 INFO (qtp395168789-17723) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2690663 INFO (qtp395168789-17724) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2690893 INFO (qtp395168789-17719) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2691123 INFO (qtp395168789-17720) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/replication params=3D{c= ommand=3Ddetails} status=3D0 QTime=3D0=20 [junit4] 2> 2691353 INFO (qtp395168789-17721) [ x:collection1] o.a= .s.c.S.Request [collection1] webapp=3D/solr path=3D/re [...truncated too long message...] 2> 9917603 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[B46= 6C333BCD6B09]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing Ind= exWriter with IndexWriterCloser [junit4] 2> 9917603 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.SolrCore [collectio= n1] Closing main searcher on request. [junit4] 2> 9917605 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirectoryFac= tory Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 9917605 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirectoryFac= tory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerB= ackup_B466C333BCD6B09-001\solr-instance-002\.\collection1\data\ [CachedDir<= >] [junit4] 2> 9917605 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirectoryFac= tory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandle= rBackup_B466C333BCD6B09-001\solr-instance-002\.\collection1\data\ [junit4] 2> 9917605 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirectoryFac= tory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandlerB= ackup_B466C333BCD6B09-001\solr-instance-002\.\collection1\data\index [Cache= dDir<>] [junit4] 2> 9917605 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.a.s.c.CachingDirectoryFac= tory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandle= rBackup_B466C333BCD6B09-001\solr-instance-002\.\collection1\data\index [junit4] 2> 9917606 INFO (TEST-TestReplicationHandlerBackup.doTestBac= kup-seed#[B466C333BCD6B09]) [ x:collection1] o.e.j.s.h.ContextHandler St= opped o.e.j.s.ServletContextHandler@1afeddfb{/solr,null,UNAVAILABLE} [junit4] 2> 9917607 INFO (SUITE-TestReplicationHandlerBackup-seed#[B4= 66C333BCD6B09]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\Jenkins= Slave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp= \solr.handler.TestReplicationHandlerBackup_B466C333BCD6B09-001 [junit4] 2> Aug 26, 2015 11:54:56 AM com.carrotsearch.randomizedtestin= g.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene53): {name= =3DPostingsFormat(name=3DMemory doPackFST=3D false), id=3DPostingsFormat(na= me=3DMemory doPackFST=3D false)}, docValues:{}, sim=3DRandomSimilarityProvi= der(queryNorm=3Dtrue,coord=3Dcrazy): {}, locale=3Dms_MY, timezone=3DAmerica= /Glace_Bay [junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_60 (64-= bit)/cpus=3D3,threads=3D1,free=3D79096232,total=3D408420352 [junit4] 2> NOTE: All tests run in this JVM: [RequestHandlersTest, Tes= tAtomicUpdateErrorCases, HdfsLockFactoryTest, TestNoOpRegenerator, TestColl= ectionAPI, TestManagedResource, TestSimpleQParserPlugin, TestPivotHelperCod= e, TestLFUCache, LeaderElectionTest, TestFieldResource, TestGroupingSearch,= DirectSolrConnectionTest, CdcrReplicationHandlerTest, TestIndexSearcher, S= hardRoutingTest, TestSolrCLIRunExample, SmileWriterTest, TestLazyCores, Tes= tHashPartitioner, TestSimpleTrackingShardHandler, SOLR749Test, DistanceFunc= tionTest, TestElisionMultitermQuery, ShowFileRequestHandlerTest, UUIDFieldT= est, TestCharFilters, TestRandomMergePolicy, AliasIntegrationTest, TestBina= ryField, TestSweetSpotSimilarityFactory, TestHighlightDedupGrouping, CacheH= eaderTest, TestManagedSchemaFieldResource, TestRandomDVFaceting, TestBM25Si= milarityFactory, TestLRUStatsCache, JSONWriterTest, DistributedDebugCompone= ntTest, TestScoreJoinQPNoScore, AddBlockUpdateTest, TestSortByMinMaxFunctio= n, TestHashQParserPlugin, TestCustomDocTransformer, RuleEngineTest, CloudML= TQParserTest, TestSolrConfigHandlerCloud, OutputWriterTest, IndexSchemaRunt= imeFieldTest, ExplicitHLLTest, TestStressReorder, DistributedVersionInfoTes= t, HdfsWriteToMultipleCollectionsTest, TestSchemaResource, CoreAdminRequest= StatusTest, HdfsChaosMonkeySafeLeaderTest, TestJmxIntegration, BigEndianAsc= endingWordDeserializerTest, TestRestManager, FieldAnalysisRequestHandlerTes= t, DocValuesMissingTest, TestFieldTypeResource, TestRTimerTree, BlockCacheT= est, DistributedFacetPivotSmallTest, TestBulkSchemaAPI, TestSchemaVersionRe= source, UniqFieldsUpdateProcessorFactoryTest, TestArbitraryIndexDir, Exitab= leDirectoryReaderTest, XmlUpdateRequestHandlerTest, CircularListTest, TestC= lusterStateMutator, SampleTest, URLClassifyProcessorTest, AsyncMigrateRoute= KeyTest, TestExactSharedStatsCache, TestFileDictionaryLookup, TestUpdate, T= estCollapseQParserPlugin, TestDynamicLoading, TestAuthorizationFramework, R= ecoveryAfterSoftCommitTest, TestDistribIDF, MigrateRouteKeyTest, TestPerFie= ldSimilarity, TestSolrConfigHandler, TestTrackingShardHandlerFactory, NoCac= heHeaderTest, PreAnalyzedFieldTest, TestCSVResponseWriter, TestWordDelimite= rFilterFactory, HLLUtilTest, TestCollationField, TestCryptoKeys, TestAnalyz= eInfixSuggestions, TestCloudPivotFacet, DistributedSpellCheckComponentTest,= LeaderInitiatedRecoveryOnCommitTest, TestLRUCache, PeerSyncTest, TestNamed= UpdateProcessors, TestCoreContainer, SynonymTokenizerTest, TestRandomReques= tDistribution, SimpleCollectionCreateDeleteTest, RequiredFieldsTest, TestBi= naryResponseWriter, DistributedIntervalFacetingTest, ScriptEngineTest, Anal= ysisAfterCoreReloadTest, TestDownShardTolerantSearch, CdcrUpdateLogTest, Sy= ncSliceTest, TestRawResponseWriter, TestFieldCollectionResource, TestCSVLoa= der, TestCustomSort, TestSurroundQueryParser, TestSmileRequest, TestConfigS= ets, SolrCoreCheckLockOnStartupTest, TestPhraseSuggestions, TestStressLucen= e, TestSortingResponseWriter, JavabinLoaderTest, OverseerTest, ChaosMonkeyN= othingIsSafeTest, DistanceUnitsTest, DOMUtilTest, TestReplicationHandler, S= hardRoutingCustomTest, TestDistributedSearch, JsonLoaderTest, TestPartialUp= dateDeduplication, CSVRequestHandlerTest, TestQuerySenderListener, UpdatePa= ramsTest, TestSolrIndexConfig, CopyFieldTest, BadComponentTest, TestStressR= ecovery, TestDocSet, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, TestP= ostingsSolrHighlighter, EchoParamsTest, TestIBSimilarityFactory, TestFastWr= iter, TimeZoneUtilsTest, OpenExchangeRatesOrgProviderTest, TestFastLRUCache= , PrimUtilsTest, ClusterStateTest, TestUtils, ZkNodePropsTest, SystemInfoHa= ndlerTest, DistributedMLTComponentTest, TestTolerantSearch, ActionThrottleT= est, AssignTest, CdcrReplicationDistributedZkTest, CdcrRequestHandlerTest, = CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, Connec= tionManagerTest, CustomCollectionTest, OverriddenZkACLAndCredentialsProvide= rsTest, RemoteQueryErrorTest, ReplicationFactorTest, RollingRestartTest, Sh= aredFSAutoReplicaFailoverTest, SolrCloudExampleTest, SolrXmlInZkTest, TestA= uthenticationFramework, TestReplicaProperties, TestRequestStatusCollectionA= PI, TriLevelCompositeIdRoutingTest, CachingDirectoryFactoryTest, QueryResul= tKeyTest, TestConfigSetProperties, TestImplicitCoreProperties, TestInfoStre= amLogging, TestNRTOpen, TestReloadAndDeleteDocs, TestSolrDynamicMBean, Test= SolrXml, TestConfigReload, TestReplicationHandlerBackup] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestReplicati= onHandlerBackup -Dtests.seed=3DB466C333BCD6B09 -Dtests.slow=3Dtrue -Dtests.= locale=3Dms_MY -Dtests.timezone=3DAmerica/Glace_Bay -Dtests.asserts=3Dtrue = -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 0.00s J0 | TestReplicationHandlerBackup (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded = (>=3D 7200000 msec). [junit4] > =09at __randomizedtesting.SeedInfo.seed([B466C333BCD6B09]:= 0) [junit4] Completed [540/540] on J0 in 7250.10s, 2 tests, 2 errors <<< FA= ILURES! [...truncated 11 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:775: Th= e following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:719: Th= e 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:23= 3: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build= .xml:514: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-bui= ld.xml:1432: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-bui= ld.xml:987: There were test failures: 540 suites, 2136 tests, 1 suite-level= error, 1 error, 78 ignored (42 assumptions) Total time: 181 minutes 9 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 ------=_Part_16_328562438.1440590135304 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_16_328562438.1440590135304--