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 360E0F405 for ; Sun, 28 Apr 2013 09:09:21 +0000 (UTC) Received: (qmail 73065 invoked by uid 500); 28 Apr 2013 09:09:18 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 72726 invoked by uid 500); 28 Apr 2013 09:09:17 -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 72691 invoked by uid 99); 28 Apr 2013 09:09:16 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 28 Apr 2013 09:09:16 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=NORMAL_HTTP_TO_IP,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of jenkins@thetaphi.de designates 188.138.97.18 as permitted sender) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 28 Apr 2013 09:08:54 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id 8DB0214AA043 for ; Sun, 28 Apr 2013 09:08:33 +0000 (UTC) Date: Sun, 28 Apr 2013 09:08:20 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <140593509.127.1367140113454.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <913649623.109.1367113695084.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <913649623.109.1367113695084.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.6.0_45) - Build # 2742 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_126_1147977186.1367140100113" X-Jenkins-Job: Lucene-Solr-4.x-Windows X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_126_1147977186.1367140100113 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/2742/ Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseParallelGC 1 tests failed. REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressRep= lication Error Message: timed out waiting for collection1 startAt time to exceed: Sun Apr 28 04:32:= 41 AST 2013 Stack Trace: java.lang.AssertionError: timed out waiting for collection1 startAt time to= exceed: Sun Apr 28 04:32:41 AST 2013 =09at __randomizedtesting.SeedInfo.seed([E00EBF2F6915D411:3BA5BFE96C3DBDA2]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestR= eplicationHandler.java:1473) =09at org.apache.solr.handler.TestReplicationHandler.doTestStressReplicatio= n(TestReplicationHandler.java:777) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:39) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:25) =09at java.lang.reflect.Method.invoke(Method.java:597) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:662) Build Log: [...truncated 8441 lines...] [junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:29.991; org.apache.solr.SolrT= estCaseJ4; ###Starting testEmptyCommits [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.003; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.008; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57512 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.008; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.009; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.009; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137949992\master [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.009; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137949992\master\= solr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.009; org.apache.solr.core.= CoreContainer; New CoreContainer 220931271 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.010; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.010; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137949992\master\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.010; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137949992\master\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.118; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.118; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.119; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.119; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.119; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.120; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.120; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.120; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.121; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.121; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.128; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.133; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137949992\master\c= ollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.134; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137949992\master\collecti= on1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.187; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.258; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.259; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.262; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.286; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.288; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.288; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137949992\master\collection1\, dataD= ir=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-13671379= 49992\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.288; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.288; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.290; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137949992\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.290; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137949992\master\collection= 1\data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:30.290; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137949992\master\collection1\data\inde= x' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.290; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137949992\master\collection1\data\= index [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.291; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.291; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.292; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.293; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.293; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.293; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.293; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.294; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.300; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.318; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@61dee8db main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.319; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.319; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.320; org.apache.solr.handl= er.ReplicationHandler; Replication enabled for following config files: sche= ma.xml,xslt/dummy.xsl [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.320; org.apache.solr.handl= er.ReplicationHandler; Commits will be reserved for 10000 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.320; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.321; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.327; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.327; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.337; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@61dee8db main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.346; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.350; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57515 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.351; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.351; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.352; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137950330\slave [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.352; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\s= olr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.352; org.apache.solr.core.= CoreContainer; New CoreContainer 1577638546 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.353; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.353; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137950330\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.353; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.444; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.445; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.445; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.446; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.446; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.446; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.446; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.447; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.448; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.448; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.452; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.458; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\co= llection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.459; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\collectio= n1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.509; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.571; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.572; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.575; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.597; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.599; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.599; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137950330\slave\collection1\, dataDi= r=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-136713795= 0330\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.599; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.599; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.600; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.600; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137950330\slave\collection1= \data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:30.600; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index= ' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.601; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\i= ndex [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.603; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.604; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.606; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.606; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.606; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.607; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.607; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.607; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.609; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.613; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@112cff56 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.613; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.614; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.618; org.apache.solr.handl= er.SnapPuller; Poll Scheduled at an interval of 1000ms [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.618; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.618; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@112cff56 main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.619; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.619; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.620; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.635; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3D*:*&wt=3Djavabin&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.640; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.641; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.641; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[1]} 0 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.643; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.644; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.645; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.646; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@417ffb28 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.646; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.646; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@417ffb28 main{Stan= dardDirectoryReader(segments_2:3:nrt _0(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.647; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{commit=3Dtrue&wt=3Djavabin&version=3D2} {commit=3D} 0 4 [junit4:junit4] 1> WARN - 2013-04-28 08:32:30.691; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.691; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.693; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.693; org.apache.solr.handl= er.SnapPuller; Master's generation: 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.693; org.apache.solr.handl= er.SnapPuller; Slave's generation: 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.693; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.705; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D2&version=3D2} sta= tus=3D0 QTime=3D9=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.706; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 10 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.706; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\i= ndex.20130428043230706 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.707; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@2ce038b6 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@1c2ce9f9) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.709; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.712; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.715; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.718; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.721; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_2&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.724; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.726; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.730; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.735; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.738; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.739; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.742; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.743; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.744; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.745; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.745; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.746; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@2f880917 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.747; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@2f880917 main{Stan= dardDirectoryReader(segments_2:3:nrt _0(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.748; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\index.201= 30428043230706 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.748; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index.2= 0130428043230706 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.749; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\colle= ction1\data\index.20130428043230706 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.749; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57512/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D59=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.784; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3Dname:empty1&wt=3Djavabin&version=3D2} hits=3D1 status=3D0 QTime=3D2= 0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.789; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.792; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.798; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.800; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D5=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.802; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.805; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.808; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.809; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.810; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.811; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@12dace3b main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.811; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.811; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@12dace3b main{Stan= dardDirectoryReader(segments_2:3:nrt _0(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.812; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{commit=3Dtrue&wt=3Djavabin&version=3D2} {commit=3D} 0 4 [junit4:junit4] 1> WARN - 2013-04-28 08:32:30.816; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.817; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.822; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.823; org.apache.solr.handl= er.SnapPuller; Master's generation: 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.823; org.apache.solr.handl= er.SnapPuller; Slave's generation: 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.824; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.827; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D3&version=3D2} sta= tus=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.829; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 10 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.829; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\i= ndex.20130428043230829 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.830; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@2bdf7750 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@3fec7107) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.830; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.831; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.831; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.832; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.832; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.832; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.832; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.833; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.837; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_3&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.838; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.839; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.844; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.844; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.845; org.apache.solr.updat= e.DefaultSolrCoreState; Closing old IndexWriter... core=3Dcollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.849; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.850; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.850; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.851; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@1c6b7657 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.852; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@1c6b7657 main{Stan= dardDirectoryReader(segments_3:3:nrt _0(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.853; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\index.201= 30428043230829 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.853; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index.2= 0130428043230829 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.854; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\colle= ction1\data\index.20130428043230829 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.855; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57512/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D40=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.861; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[2]} 0 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.865; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.868; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D3 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.869; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.871; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@42b113b8 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.872; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.872; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@42b113b8 main{Stan= dardDirectoryReader(segments_4:5:nrt _0(4.4):C1 _1(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.874; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{commit=3Dtrue&wt=3Djavabin&version=3D2} {commit=3D} 0 10 [junit4:junit4] 1> WARN - 2013-04-28 08:32:30.878; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.878; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.882; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.883; org.apache.solr.handl= er.SnapPuller; Master's generation: 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.883; org.apache.solr.handl= er.SnapPuller; Slave's generation: 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.883; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.889; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D4&version=3D2} sta= tus=3D0 QTime=3D3=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.891; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 19 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.891; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\i= ndex.20130428043230891 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.892; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@3cc48550 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@64157d2) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.896; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.898; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.901; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.903; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.907; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.912; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.917; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.923; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.927; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.928; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.928; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.929; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.930; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.930; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.930; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.933; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.934; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.936; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.940; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_4&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.942; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.947; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.947; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.947; org.apache.solr.updat= e.DefaultSolrCoreState; Closing old IndexWriter... core=3Dcollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.951; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D3 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.952; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.952; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.954; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@3ff96afb main [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.955; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@3ff96afb main{Stan= dardDirectoryReader(segments_4:5:nrt _0(4.4):C1 _1(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.956; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\index.201= 30428043230891 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.956; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index.2= 0130428043230891 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.956; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\colle= ction1\data\index.20130428043230891 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.958; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57512/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D81=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.963; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3Dname:empty2&wt=3Djavabin&version=3D2} hits=3D1 status=3D0 QTime=3D2= =20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.967; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.973; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.980; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.982; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D6=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.985; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.990; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.994; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[3]} 0 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:30.997; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dfalse= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommi= t=3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.001; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D4 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_5,generation=3D5} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.002; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.003; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.004; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@696f551d realtime [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.004; org.apache.solr.handl= er.SnapPuller; Slave in sync with master. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.005; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.007; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{openSearcher=3Dfalse&commit=3Dtrue&wt=3Djavabin&version=3D2} {co= mmit=3D} 0 10 [junit4:junit4] 1> WARN - 2013-04-28 08:32:31.010; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.010; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.014; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.015; org.apache.solr.handl= er.SnapPuller; Master's generation: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.015; org.apache.solr.handl= er.SnapPuller; Slave's generation: 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.016; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.018; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D5&version=3D2} sta= tus=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.020; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 28 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.021; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\i= ndex.20130428043231021 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.022; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@33f46cf7 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@6f3930b4) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.027; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D5} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.028; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.029; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.032; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.036; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.041; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.042; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.042; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.042; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.043; org.apache.solr.handl= er.SnapPuller; Skipping download for _1_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.043; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.047; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.052; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D5} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.054; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.058; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.058; org.apache.solr.handl= er.SnapPuller; Skipping download for _1_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.062; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.067; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.069; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.069; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.070; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.071; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.071; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.071; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.072; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.076; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_5&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D5} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.077; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.077; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.077; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.082; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.082; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.083; org.apache.solr.updat= e.DefaultSolrCoreState; Closing old IndexWriter... core=3Dcollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.087; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D3 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_5,generation=3D5} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.087; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.087; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.088; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@a10a9cd main [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.088; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@a10a9cd main{Stand= ardDirectoryReader(segments_5:7:nrt _0(4.4):C1 _1(4.4):C1 _2(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.089; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\index.201= 30428043231021 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.089; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index.2= 0130428043231021 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.089; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\colle= ction1\data\index.20130428043231021 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.090; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57512/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D81=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.094; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3Dname:empty3&wt=3Djavabin&version=3D2} hits=3D0 status=3D0 QTime=3D1= =20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.097; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3Dname:empty3&wt=3Djavabin&version=3D2} hits=3D1 status=3D0 QTime=3D1= =20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.100; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[4]} 0 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.103; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.106; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D5 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_5,generation=3D5} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@831ebd9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@7b16ea43),segFN=3Dsegments_6,generation=3D6} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.106; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 6 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.107; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@20b13914 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.108; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.108; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@20b13914 main{Stan= dardDirectoryReader(segments_6:9:nrt _0(4.4):C1 _1(4.4):C1 _2(4.4):C1 _3(4.= 4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.109; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{commit=3Dtrue&wt=3Djavabin&version=3D2} {commit=3D} 0 6 [junit4:junit4] 1> WARN - 2013-04-28 08:32:31.111; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.111; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.113; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.114; org.apache.solr.handl= er.SnapPuller; Master's generation: 6 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.114; org.apache.solr.handl= er.SnapPuller; Slave's generation: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.114; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.117; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D6&version=3D2} sta= tus=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.117; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 37 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.118; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137950330\slave\collection1\data\i= ndex.20130428043231118 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.118; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@2e92a648 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@2b0244c) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.118; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.121; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.124; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.126; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.128; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.131; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.134; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.136; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.136; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.136; org.apache.solr.handl= er.SnapPuller; Skipping download for _2_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.137; org.apache.solr.handl= er.SnapPuller; Skipping download for _2_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.137; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.137; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.138; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.138; org.apache.solr.handl= er.SnapPuller; Skipping download for _1_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.139; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.139; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.140; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.140; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.140; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.140; org.apache.solr.handl= er.SnapPuller; Skipping download for _1_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.140; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.143; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.145; org.apache.solr.handl= er.SnapPuller; Skipping download for _2.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.145; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.148; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D6} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.150; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.150; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.150; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.151; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.151; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.152; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.155; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D6} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.161; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_3.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D6} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.166; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_6&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D6} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.178; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.179; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.179; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.183; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.183; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.184; org.apache.solr.updat= e.DefaultSolrCoreState; Closing old IndexWriter... core=3Dcollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.243; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D4 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_5,generation=3D5} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@38e2a040 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@34806f42),segFN=3Dsegments_6,generation=3D6} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.245; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 6 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.246; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.250; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@3eeba269 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.251; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@3eeba269 main{Stan= dardDirectoryReader(segments_6:9:nrt _0(4.4):C1 _1(4.4):C1 _2(4.4):C1 _3(4.= 4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.252; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\index.201= 30428043231118 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.252; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index.2= 0130428043231118 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.254; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137950330\slave\colle= ction1\data\index.20130428043231118 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.255; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57512/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D145=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.263; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3Dname:(empty1+empty2+empty3+empty4)&wt=3Djavabin&version=3D2} hits= =3D4 status=3D0 QTime=3D4=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.270; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3Dname:(empty1+empty2+empty3+empty4)&wt=3Djavabin&version=3D2} hits= =3D4 status=3D0 QTime=3D4=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.274; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.280; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.288; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.291; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D7=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.297; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.301; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.302; org.apache.solr.SolrT= estCaseJ4; ###Ending testEmptyCommits [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.316; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D220931271 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.319; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6c1= fa59f [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.320; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D5,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D4,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.321; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.322; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.323; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.325; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.326; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.327; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137949992\master\collection1\data\index [C= achedDir<>= ] [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.327; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137949992\master\collection1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.327; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137949992\master\collection1\data\ [Cached= Dir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.328; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137949992\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.328; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.396; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D1577638546 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.398; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@584= ced04 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.399; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D0,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D0,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.400; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.402; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.403; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.407; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.458; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.460; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\ [CachedD= ir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.461; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.462; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137950330\slave\collection1\data\index [Ca= chedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.463; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137950330\slave\collection1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.464; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.548; org.apache.solr.SolrT= estCaseJ4; ###Starting doTestDetails [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.560; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.564; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57522 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.565; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.565; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.565; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137951549\master [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.566; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137951549\master\= solr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.566; org.apache.solr.core.= CoreContainer; New CoreContainer 1831521332 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.567; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.568; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137951549\master\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.568; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137951549\master\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.700; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.701; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.701; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.702; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.702; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.702; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.703; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.703; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.703; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.703; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.712; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.719; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137951549\master\c= ollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.721; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137951549\master\collecti= on1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.778; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.851; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.852; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.856; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.884; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.887; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.887; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137951549\master\collection1\, dataD= ir=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-13671379= 51549\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.887; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.888; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.888; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951549\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.888; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137951549\master\collection= 1\data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:31.889; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137951549\master\collection1\data\inde= x' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.889; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951549\master\collection1\data\= index [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.890; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.890; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.891; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.891; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.891; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.891; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.891; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.891; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.898; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.902; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@45873320 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.902; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.902; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.903; org.apache.solr.handl= er.ReplicationHandler; Replication enabled for following config files: sche= ma.xml,xslt/dummy.xsl [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.903; org.apache.solr.handl= er.ReplicationHandler; Commits will be reserved for 10000 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.903; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.903; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@45873320 main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.905; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.905; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.906; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.928; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.931; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57525 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.931; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.932; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.932; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137951912\slave [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.933; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\s= olr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.933; org.apache.solr.core.= CoreContainer; New CoreContainer 1850369317 [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.933; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.934; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137951912\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:31.934; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.043; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.043; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.044; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.044; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.044; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.045; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.045; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.045; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.046; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.046; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.050; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.056; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\co= llection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.058; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\collectio= n1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.126; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.221; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.222; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.225; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.252; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.254; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.254; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137951912\slave\collection1\, dataDi= r=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-136713795= 1912\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.254; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.254; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.262; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951912\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.262; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137951912\slave\collection1= \data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:32.263; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137951912\slave\collection1\data\index= ' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.263; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951912\slave\collection1\data\i= ndex [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.265; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.265; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.266; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.266; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.266; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.266; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.266; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.267; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.269; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.273; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@628d28f1 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.273; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.273; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.275; org.apache.solr.handl= er.SnapPuller; Poll Scheduled at an interval of 1000ms [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.275; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.276; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.277; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.277; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.277; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@628d28f1 main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.300; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/select params=3D{sort=3Did+d= esc&q=3D*:*&wt=3Djavabin&version=3D2} hits=3D0 status=3D0 QTime=3D2=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.302; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.314; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.315; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D4=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.319; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.319; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.320; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[0]} 0 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.323; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.324; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.324; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.325; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@77a7ed63 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.325; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.326; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@77a7ed63 main{Stan= dardDirectoryReader(segments_2:3:nrt _0(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.326; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{waitSearcher=3Dtrue&commit=3Dtrue&wt=3Djavabin&version=3D2&softC= ommit=3Dfalse} {commit=3D} 0 3 [junit4:junit4] 1> WARN - 2013-04-28 08:32:32.329; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.329; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.331; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.332; org.apache.solr.handl= er.SnapPuller; Master's generation: 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.332; org.apache.solr.handl= er.SnapPuller; Slave's generation: 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.332; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.336; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D2&version=3D2} sta= tus=3D0 QTime=3D2=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.337; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 10 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.337; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951912\slave\collection1\data\i= ndex.20130428043232337 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.337; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@49ab0137 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@cbff27f) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.351; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.355; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.358; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.361; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.364; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_2&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.367; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.370; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.374; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.377; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.380; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_0.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.381; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.383; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.383; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.384; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.384; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.384; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.385; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@4643ac23 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.385; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@4643ac23 main{Stan= dardDirectoryReader(segments_2:3:nrt _0(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.386; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951912\slave\collection1\data\index.201= 30428043232337 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.386; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951912\slave\collection1\data\index.2= 0130428043232337 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.386; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\colle= ction1\data\index.20130428043232337 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.387; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57522/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D59=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.392; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.393; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D2=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.396; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[1]} 0 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.399; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.403; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.403; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.405; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@4e124a9 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.405; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.405; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@4e124a9 main{Stand= ardDirectoryReader(segments_3:5:nrt _0(4.4):C1 _1(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.406; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{waitSearcher=3Dtrue&commit=3Dtrue&wt=3Djavabin&version=3D2&softC= ommit=3Dfalse} {commit=3D} 0 7 [junit4:junit4] 1> WARN - 2013-04-28 08:32:32.407; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.407; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.410; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.410; org.apache.solr.handl= er.SnapPuller; Master's generation: 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.410; org.apache.solr.handl= er.SnapPuller; Slave's generation: 2 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.410; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.412; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D3&version=3D2} sta= tus=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.413; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 19 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.414; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951912\slave\collection1\data\i= ndex.20130428043232414 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.414; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@1261db2b lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@2dd4708d) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.417; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.418; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.421; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.422; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.424; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.427; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.431; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.435; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.438; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.439; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.439; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.439; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.439; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.439; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.439; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.442; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.443; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.445; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_3&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.448; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_1.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D3} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.449; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.451; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.451; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.452; org.apache.solr.updat= e.DefaultSolrCoreState; Closing old IndexWriter... core=3Dcollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.453; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.453; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.453; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.454; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@70f52030 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.455; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@70f52030 main{Stan= dardDirectoryReader(segments_3:5:nrt _0(4.4):C1 _1(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.455; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951912\slave\collection1\data\index.201= 30428043232414 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.455; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951912\slave\collection1\data\index.2= 0130428043232414 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.455; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\colle= ction1\data\index.20130428043232414 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.456; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57522/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D49=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.462; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.463; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D3=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.466; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{wt=3Djavabin&version=3D2} {add=3D[2]} 0 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.469; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit= =3Dfalse} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.471; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D3 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@389ed39 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@21b7e734),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.472; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.473; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@22d6d956 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.473; org.apache.solr.updat= e.DirectUpdateHandler2; end_commit_flush [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.473; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@22d6d956 main{Stan= dardDirectoryReader(segments_4:7:nrt _0(4.4):C1 _1(4.4):C1 _2(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.474; org.apache.solr.updat= e.processor.LogUpdateProcessor; [collection1] webapp=3D/solr path=3D/update= params=3D{waitSearcher=3Dtrue&commit=3Dtrue&wt=3Djavabin&version=3D2&softC= ommit=3Dfalse} {commit=3D} 0 5 [junit4:junit4] 1> WARN - 2013-04-28 08:32:32.476; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.476; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.478; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.479; org.apache.solr.handl= er.SnapPuller; Master's generation: 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.479; org.apache.solr.handl= er.SnapPuller; Slave's generation: 3 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.479; org.apache.solr.handl= er.SnapPuller; Starting replication process [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.482; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D4&version=3D2} sta= tus=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.484; org.apache.solr.handl= er.SnapPuller; Number of files in latest index in master: 28 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.484; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137951912\slave\collection1\data\i= ndex.20130428043232484 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.484; org.apache.solr.handl= er.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.= RAMDirectory@4a7c8353 lockFactory=3Dorg.apache.lucene.store.SingleInstanceL= ockFactory@4645a73) fullCopy=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.486; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.si&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/replic= ation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.487; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.488; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.490; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.tim&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.495; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2_nrm.cfe&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.498; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2_nrm.cfs&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.499; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.500; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.500; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.500; org.apache.solr.handl= er.SnapPuller; Skipping download for _1_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.500; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fnm because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.502; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.fdt&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.507; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.frq&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.508; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfs because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.512; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.fdx&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.513; org.apache.solr.handl= er.SnapPuller; Skipping download for _1_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.516; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.fnm&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.521; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3D_2.tip&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/repli= cation&generation=3D4} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.537; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.538; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.538; org.apache.solr.handl= er.SnapPuller; Skipping download for _1.si because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.539; org.apache.solr.handl= er.SnapPuller; Skipping download for _0_nrm.cfe because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.539; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tim because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.539; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.frq because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.540; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdx because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.540; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.tip because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.541; org.apache.solr.handl= er.SnapPuller; Skipping download for _0.fdt because it already exists [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.545; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{file= =3Dsegments_4&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt=3D/r= eplication&generation=3D4} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.547; org.apache.solr.handl= er.SnapPuller; Total time taken for download : 0 secs [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.552; org.apache.solr.updat= e.DefaultSolrCoreState; Creating new IndexWriter... [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.552; org.apache.solr.updat= e.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=3Dcolle= ction1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.553; org.apache.solr.updat= e.DefaultSolrCoreState; Closing old IndexWriter... core=3Dcollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.556; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_3,generation=3D3} [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@683fbf43 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@5a348b27),segFN=3Dsegments_4,generation=3D4} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.556; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.557; org.apache.solr.updat= e.DefaultSolrCoreState; New IndexWriter is ready to be used. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.558; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@31f9bcbc main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.567; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@31f9bcbc main{Stan= dardDirectoryReader(segments_4:7:nrt _0(4.4):C1 _1(4.4):C1 _2(4.4):C1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.567; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951912\slave\collection1\data\index.201= 30428043232484 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.568; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951912\slave\collection1\data\index.2= 0130428043232484 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.568; org.apache.solr.core.= CachingDirectoryFactory; Removing directory before core close: .\org.apache= .solr.handler.TestReplicationHandler$SolrInstance-1367137951912\slave\colle= ction1\data\index.20130428043232484 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.568; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{master= Url=3Dhttp://127.0.0.1:57522/solr/replication&command=3Dfetchindex&wait=3Dt= rue} status=3D0 QTime=3D93=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.581; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.584; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57533 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.585; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.585; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.585; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137952569\repeater [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.585; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137952569\repeate= r\solr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.585; org.apache.solr.core.= CoreContainer; New CoreContainer 779788499 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.587; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.587; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137952569\repeater\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.588; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137952569\repeater\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.692; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.693; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.693; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.693; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.693; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.694; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.694; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.694; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.695; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.695; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.699; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.705; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137952569\repeater= \collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.707; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137952569\repeater\collec= tion1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.765; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.831; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.832; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.835; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.858; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.860; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.860; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137952569\repeater\collection1\, dat= aDir=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-136713= 7952569\repeater\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.860; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.860; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.861; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137952569\repeater\collection1\dat= a\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.861; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137952569\repeater\collecti= on1\data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:32.861; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137952569\repeater\collection1\data\in= dex' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.862; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137952569\repeater\collection1\dat= a\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.863; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@5725a42b lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@65cb5512),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.863; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.864; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.864; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.865; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.865; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.865; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.865; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.868; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.871; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@732e9042 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.872; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.872; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> WARN - 2013-04-28 08:32:32.872; org.apache.solr.handl= er.SnapPuller; 'masterUrl' must be specified without the /replication suffi= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.872; org.apache.solr.handl= er.SnapPuller; No value set for 'pollInterval'. Timer Task not started. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.872; org.apache.solr.handl= er.ReplicationHandler; Replication enabled for following config files: sche= ma.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.872; org.apache.solr.handl= er.ReplicationHandler; Commits will be reserved for 10000 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.874; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@732e9042 main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.874; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.875; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.875; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.876; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.891; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.892; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D4=20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.909; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D779788499 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.911; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@708= 0d6b4 [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.911; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D0,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D0,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.912; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.912; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.913; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.915; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.915; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.916; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137952569\repeater\collection1\data\index = [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.916; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137952569\repeater\collection1\data\inde= x [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.917; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137952569\repeater\collection1\data\ [Cach= edDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.918; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137952569\repeater\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.920; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:32.992; org.apache.solr.SolrT= estCaseJ4; ###Ending doTestDetails [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.008; org.apache.http.impl.= client.DefaultRequestDirector; I/O exception (java.net.SocketException) cau= ght when processing request: Software caused connection abort: recv failed [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.011; org.apache.http.impl.= client.DefaultRequestDirector; Retrying request [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.008; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D1831521332 [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.013; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6b5= 43e44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.013; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D3,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D3,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.015; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.016; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.016; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.018; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.020; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.020; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951549\master\collection1\data\ [Cached= Dir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.021; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951549\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.022; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951549\master\collection1\data\index [C= achedDir<>= ] [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.023; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951549\master\collection1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.024; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.093; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D1850369317 [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.095; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4b2= b09f4 [junit4:junit4] 1> ERROR - 2013-04-28 08:32:33.967; org.apache.solr.handl= er.SnapPuller; Master at: http://127.0.0.1:57522/solr is not available. Ind= ex fetch failed. Exception: org.apache.solr.client.solrj.SolrServerExceptio= n: Server refused connection at: http://127.0.0.1:57522/solr [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.970; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D0,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D0,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.972; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.972; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.973; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.975; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.976; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.977; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951912\slave\collection1\data\ [CachedD= ir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.978; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951912\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.979; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137951912\slave\collection1\data\index [Ca= chedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.980; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137951912\slave\collection1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:33.981; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.069; org.apache.solr.SolrT= estCaseJ4; ###Starting doTestStressReplication [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.081; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.084; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57538 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.085; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.085; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.086; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137954069\master [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.086; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137954069\master\= solr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.086; org.apache.solr.core.= CoreContainer; New CoreContainer 1585183078 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.088; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.088; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137954069\master\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.088; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137954069\master\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.208; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.208; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.208; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.209; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.209; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.210; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.210; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.210; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.211; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.211; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.219; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.226; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137954069\master\c= ollection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.227; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137954069\master\collecti= on1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.301; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.386; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.386; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.390; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.415; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.416; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.417; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137954069\master\collection1\, dataD= ir=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-13671379= 54069\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.418; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.418; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.418; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137954069\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.419; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137954069\master\collection= 1\data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:34.419; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137954069\master\collection1\data\inde= x' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.419; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137954069\master\collection1\data\= index [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.420; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@71b17530 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@3560d2d1),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.420; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.421; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.422; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.423; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.423; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.423; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.423; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.429; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.433; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@77aa0759 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.433; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.433; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.433; org.apache.solr.handl= er.ReplicationHandler; Replication enabled for following config files: sche= ma.xml,xslt/dummy.xsl [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.433; org.apache.solr.handl= er.ReplicationHandler; Commits will be reserved for 10000 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.434; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.434; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@77aa0759 main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.435; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.435; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.436; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.457; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.461; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57541 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.462; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.462; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.462; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137954441\slave [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.462; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\s= olr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.462; org.apache.solr.core.= CoreContainer; New CoreContainer 654212203 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.464; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.464; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137954441\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.465; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.555; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.555; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.555; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.555; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.556; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.556; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.556; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.557; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.557; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.558; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.560; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.567; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\co= llection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.568; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\collectio= n1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.619; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.678; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.678; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.682; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.701; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.703; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.703; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137954441\slave\collection1\, dataDi= r=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-136713795= 4441\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.703; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.704; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.704; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137954441\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.704; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137954441\slave\collection1= \data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:34.704; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137954441\slave\collection1\data\index= ' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.705; org.apache.solr.core.= CachingDirectoryFactory; return new directory for .\org.apache.solr.handler= .TestReplicationHandler$SolrInstance-1367137954441\slave\collection1\data\i= ndex [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.706; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@34a63837 lockFactory=3Dorg.apache.lucene.store.SingleInstance= LockFactory@45c3d185),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.706; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.707; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.708; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.708; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.708; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.708; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.709; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.711; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.714; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@9db74a9 main [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.715; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.715; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.716; org.apache.solr.handl= er.SnapPuller; Poll Scheduled at an interval of 1000ms [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.716; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.716; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@9db74a9 main{Stand= ardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.717; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.717; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.717; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.735; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D654212203 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.737; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@67f= 7bbdf [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.739; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D0,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D0,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.739; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.741; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.741; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.743; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.743; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.745; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137954441\slave\collection1\data\index [Ca= chedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.745; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137954441\slave\collection1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.746; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137954441\slave\collection1\data\ [CachedD= ir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.747; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137954441\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.749; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.805; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.811; org.eclipse.jetty.ser= ver.AbstractConnector; Started SelectChannelConnector@127.0.0.1:57544 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.814; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.815; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.816; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: .\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137954441\slave [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.817; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: C:\Users\JenkinsSl= ave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\s= olr.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.817; org.apache.solr.core.= CoreContainer; New CoreContainer 1451287680 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.818; org.apache.solr.core.= CoreContainer$Initializer; no solr.xml found. using default old-style solr.= xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.819; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '.\org.apache.solr.ha= ndler.TestReplicationHandler$SolrInstance-1367137954441\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.820; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.938; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.939; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.939; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.939; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.940; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.940; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.940; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.941; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.941; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.942; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D0&connTimeout=3D0&retr= y=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.945; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.952; org.apache.solr.core.= CoreContainer; Creating SolrCore 'collection1' using instanceDir: .\org.apa= che.solr.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\co= llection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:34.952; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '.\org.apache.sol= r.handler.TestReplicationHandler$SolrInstance-1367137954441\slave\collectio= n1\' [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.004; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_44 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.068; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.069; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.073; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.094; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.096; org.apache.solr.core.= SolrCore; solr.NRTCachingDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.096; org.apache.solr.core.= SolrCore; [collection1] Opening new SolrCore at .\org.apache.solr.handler.T= estReplicationHandler$SolrInstance-1367137954441\slave\collection1\, dataDi= r=3D.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-136713795= 4441\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.097; org.apache.solr.core.= SolrCore; JMX monitoring not detected for core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.097; org.apache.solr.core.= SolrCore; solr.NRTCachingDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.097; org.apache.solr.core.= CachingDirectoryFactory; return new directory for C:\Users\JenkinsSlave\wor= kspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137954441\slave\collection= 1\data [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.098; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D.\org.apache.solr.= handler.TestReplicationHandler$SolrInstance-1367137954441\slave\collection1= \data\index/ [junit4:junit4] 1> WARN - 2013-04-28 08:32:35.099; org.apache.solr.core.= SolrCore; [collection1] Solr index directory '.\org.apache.solr.handler.Tes= tReplicationHandler$SolrInstance-1367137954441\slave\collection1\data\index= ' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.099; org.apache.solr.core.= CachingDirectoryFactory; return new directory for C:\Users\JenkinsSlave\wor= kspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr= .handler.TestReplicationHandler$SolrInstance-1367137954441\slave\collection= 1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.113; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 1> =09commit{dir=3DNRTCachingDirectory(org.apache.lucene.= store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows= \solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandle= r$SolrInstance-1367137954441\slave\collection1\data\index lockFactory=3Dorg= .apache.lucene.store.SingleInstanceLockFactory@213fdeca; maxCacheMB=3D48.0 = maxMergeSizeMB=3D4.0),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.114; org.apache.solr.core.= SolrDeletionPolicy; newest commit generation =3D 1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.115; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.115; org.apache.solr.core.= RequestHandlers; created defaults: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.115; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.115; org.apache.solr.core.= RequestHandlers; created lazy: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.116; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.116; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.126; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.137; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@1189857e main [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.137; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.137; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.137; org.apache.solr.handl= er.ReplicationHandler; Commits will be reserved for 10000 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.138; org.apache.solr.core.= CoreContainer; registering core: collection1 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.138; org.apache.solr.core.= SolrCore; [collection1] Registered new searcher Searcher@1189857e main{Stan= dardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.139; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-4.x-Windows\solr\build\solr-core\test\J0 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.139; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.139; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.167; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D1585183078 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.168; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7e7= bb9f [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.168; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D0,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D0,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D0,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.169; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.169; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.169; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.170; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.170; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.170; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137954069\master\collection1\data\ [Cached= Dir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.170; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137954069\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.170; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367137954069\master\collection1\data\index [C= achedDir<>= ] [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.171; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367137954069\master\collection1\data\index [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.172; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.227; org.eclipse.jetty.ser= ver.Server; jetty-8.1.10.v20130312 [junit4:junit4] 1> INFO - 2013-04-28 08:32:35.238; org.eclipse.jetty [...truncated too long message...] ore.SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{co= mmand=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version= =3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.914; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.917; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Dindexversion&_trace=3DassertVersions&wt=3Djavabin&qt=3D/replication&ver= sion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.923; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.924; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D3=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.932; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{slave= =3Dfalse&command=3Ddetails&wt=3Djavabin&qt=3D/replication&version=3D2} stat= us=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.933; org.apache.solr.core.= SolrCore; [collection1] webapp=3D/solr path=3D/replication params=3D{comman= d=3Ddetails&_trace=3DgetDetails&wt=3Djavabin&qt=3D/replication&version=3D2}= status=3D0 QTime=3D6=20 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.934; org.apache.solr.SolrT= estCaseJ4; ###Ending doTestSnapPullWithMasterUrl [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.948; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D50738847 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.951; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c1= 98fc5 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.952; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D2,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D496,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D1,c= umulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.953; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.953; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.954; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.957; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.958; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.958; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367138038482\master\collection1\data\ [Cached= Dir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.960; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367138038482\master\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.960; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367138038482\master\collection1\data\index.20= 130428043401688 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.961; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367138038482\master\collection1\data\index.= 20130428043401688 [junit4:junit4] 1> INFO - 2013-04-28 08:34:01.962; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.030; org.apache.solr.core.= CoreContainer; Shutting down CoreContainer instance=3D631263306 [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.032; org.apache.solr.core.= SolrCore; [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@513= d09cd [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.032; org.apache.solr.updat= e.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3D2,autocommit= s=3D0,soft autocommits=3D0,optimizes=3D0,rollbacks=3D0,expungeDeletes=3D0,d= ocsPending=3D0,adds=3D0,deletesById=3D0,deletesByQuery=3D0,errors=3D0,cumul= ative_adds=3D3,cumulative_deletesById=3D0,cumulative_deletesByQuery=3D1,cum= ulative_errors=3D0} [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.034; org.apache.solr.updat= e.SolrCoreState; Closing SolrCoreState [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.034; org.apache.solr.updat= e.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing Ind= exWriter [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.035; org.apache.solr.updat= e.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.038; org.apache.solr.core.= SolrCore; [collection1] Closing main searcher on request. [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.038; org.apache.solr.core.= CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories curre= ntly being tracked [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.039; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367138038822\slave\collection1\data\index.201= 30428043401333 [CachedDir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.040; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367138038822\slave\collection1\data\index.2= 0130428043401333 [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.040; org.apache.solr.core.= CachingDirectoryFactory; looking to close .\org.apache.solr.handler.TestRep= licationHandler$SolrInstance-1367138038822\slave\collection1\data\ [CachedD= ir<>] [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.042; org.apache.solr.core.= CachingDirectoryFactory; Closing directory: .\org.apache.solr.handler.TestR= eplicationHandler$SolrInstance-1367138038822\slave\collection1\data\ [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.043; org.eclipse.jetty.ser= ver.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,nul= l} [junit4:junit4] 1> INFO - 2013-04-28 08:34:02.133; org.apache.solr.SolrT= estCaseJ4; ###deleteCore [junit4:junit4] 2> NOTE: test params are: codec=3DAppending, sim=3DRandom= SimilarityProvider(queryNorm=3Dfalse,coord=3Dcrazy): {}, locale=3Dde_AT, ti= mezone=3DAmerica/Virgin [junit4:junit4] 2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_= 45 (64-bit)/cpus=3D2,threads=3D1,free=3D141162584,total=3D267517952 [junit4:junit4] 2> NOTE: All tests run in this JVM: [OpenCloseCoreStressT= est, SOLR749Test, TestSolrXml, BinaryUpdateRequestHandlerTest, SuggesterTST= Test, QueryEqualityTest, TestQueryUtils, TestAddFieldRealTimeGet, TestWordD= elimiterFilterFactory, PluginInfoTest, CollectionsAPIDistributedZkTest, Pat= hHierarchyTokenizerFactoryTest, TestSort, SolrIndexConfigTest, SolrCoreTest= , PreAnalyzedFieldTest, CoreContainerCoreInitFailuresTest, HighlighterTest,= ShardRoutingTest, FieldAnalysisRequestHandlerTest, TestBinaryResponseWrite= r, DocumentAnalysisRequestHandlerTest, TestSweetSpotSimilarityFactory, Test= StressLucene, TestRTGBase, TestLRUCache, JSONWriterTest, DebugComponentTest= , TestMultiCoreConfBootstrap, SystemInfoHandlerTest, QueryParsingTest, Spat= ialFilterTest, DocValuesMultiTest, TestPhraseSuggestions, TestPHPSerialized= ResponseWriter, TestFastLRUCache, TestIndexSearcher, CSVRequestHandlerTest,= SimpleFacetsTest, TestLMJelinekMercerSimilarityFactory, ShardRoutingCustom= Test, HighlighterConfigTest, TestReplicationHandler] [junit4:junit4] Completed in 92.17s, 13 tests, 1 failure <<< FAILURES! [...truncated 780 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:384: The = following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:364: The = following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The f= ollowing error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:181:= The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.x= ml:437: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build= .xml:1243: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build= .xml:887: There were test failures: 294 suites, 1222 tests, 1 failure, 18 i= gnored (12 assumptions) Total time: 63 minutes 48 seconds Build step 'Invoke Ant' marked build as failure Description set: Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UsePar= allelGC Archiving artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_126_1147977186.1367140100113 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_126_1147977186.1367140100113--