You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/16 11:07:42 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0-ea-b102) -
Build # 3150 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3150/
Java: 64bit/jdk1.8.0-ea-b102 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Fri Aug 16 11:33:37 AST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Fri Aug 16 11:33:37 AST 2013
at __randomizedtesting.SeedInfo.seed([9A6A445567988978:41C1449362B0E0CB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1513)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:817)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9533 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 253791 T910 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 253828 T910 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 253850 T910 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64713
[junit4] 2> 253850 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 253850 T910 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 253851 T910 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master
[junit4] 2> 253851 T910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\'
[junit4] 2> 253884 T910 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\solr.xml
[junit4] 2> 253886 T910 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\solr.xml does not exist, using default configuration
[junit4] 2> 253916 T910 oasc.CoreContainer.<init> New CoreContainer 65141201
[junit4] 2> 253917 T910 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\]
[junit4] 2> 253918 T910 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 253918 T910 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 253918 T910 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 253918 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 253918 T910 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 253919 T910 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 253919 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 253919 T910 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 253919 T910 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 253919 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 253932 T920 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1
[junit4] 2> 253932 T920 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\'
[junit4] 2> 253954 T920 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 253970 T920 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 253971 T920 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 253974 T920 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 253980 T920 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 253980 T920 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 253980 T920 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 253980 T920 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 253981 T920 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 253981 T920 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index/
[junit4] 2> 253981 T920 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 253982 T920 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index
[junit4] 2> 253982 T920 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@559a57aa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65f50a67),segFN=segments_1,generation=1}
[junit4] 2> 253982 T920 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 253983 T920 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 253983 T920 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 253984 T920 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 253985 T920 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 253985 T920 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 253985 T920 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 253985 T920 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 253989 T920 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 253990 T920 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 253991 T920 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 253991 T920 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@559a57aa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65f50a67),segFN=segments_1,generation=1}
[junit4] 2> 253992 T920 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 253992 T920 oass.SolrIndexSearcher.<init> Opening Searcher@7b3f4679 main
[junit4] 2> 253992 T920 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 253992 T920 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 253993 T920 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 253993 T921 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b3f4679 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 253993 T910 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 253994 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 253994 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 254015 T910 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 254019 T910 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64716
[junit4] 2> 254020 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 254020 T910 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 254021 T910 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave
[junit4] 2> 254021 T910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\'
[junit4] 2> 254037 T910 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\solr.xml
[junit4] 2> 254038 T910 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\solr.xml does not exist, using default configuration
[junit4] 2> 254050 T910 oasc.CoreContainer.<init> New CoreContainer 1230288278
[junit4] 2> 254050 T910 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\]
[junit4] 2> 254051 T910 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 254051 T910 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 254052 T910 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 254052 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 254052 T910 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 254052 T910 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 254053 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 254053 T910 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 254053 T910 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 254054 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 254063 T932 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1
[junit4] 2> 254063 T932 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\'
[junit4] 2> 254092 T932 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 254099 T932 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 254100 T932 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 254103 T932 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 254110 T932 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 254111 T932 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 254111 T932 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\
[junit4] 2> 254111 T932 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 254111 T932 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\
[junit4] 2> 254112 T932 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index/
[junit4] 2> 254112 T932 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 254112 T932 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index
[junit4] 2> 254113 T932 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@45938071 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29f64c24),segFN=segments_1,generation=1}
[junit4] 2> 254113 T932 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 254114 T932 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 254114 T932 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 254115 T932 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 254115 T932 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 254115 T932 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 254115 T932 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 254115 T932 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 254117 T932 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 254118 T932 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 254118 T932 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 254119 T932 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@45938071 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29f64c24),segFN=segments_1,generation=1}
[junit4] 2> 254119 T932 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 254119 T932 oass.SolrIndexSearcher.<init> Opening Searcher@60c34158 main
[junit4] 2> 254126 T932 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 254126 T932 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 254126 T933 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60c34158 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 254127 T910 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 254127 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 254127 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 254146 T910 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1230288278
[junit4] 2> 254148 T910 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1fe6918
[junit4] 2> 254149 T910 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 254149 T910 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 254150 T910 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 254150 T910 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 254151 T910 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 254151 T910 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 254152 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index;done=false>>]
[junit4] 2> 254152 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index
[junit4] 2> 254152 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\;done=false>>]
[junit4] 2> 254152 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\
[junit4] 2> 254153 T910 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 254221 T910 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=65141201
[junit4] 2> 254222 T910 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@747d0579
[junit4] 2> 254222 T910 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 254223 T910 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 254223 T910 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 254223 T910 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 254225 T910 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 254225 T910 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 254226 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\;done=false>>]
[junit4] 2> 254226 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 254227 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index;done=false>>]
[junit4] 2> 254228 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index
[junit4] 2> 254229 T910 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 254282 T910 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 254287 T910 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64719
[junit4] 2> 254287 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 254288 T910 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 254288 T910 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master
[junit4] 2> 254289 T910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\'
[junit4] 2> 254307 T910 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\solr.xml
[junit4] 2> 254307 T910 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\solr.xml does not exist, using default configuration
[junit4] 2> 254320 T910 oasc.CoreContainer.<init> New CoreContainer 1072752612
[junit4] 2> 254320 T910 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\]
[junit4] 2> 254322 T910 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 254322 T910 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 254322 T910 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 254323 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 254323 T910 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 254323 T910 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 254323 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 254324 T910 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 254324 T910 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 254324 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 254331 T947 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1
[junit4] 2> 254331 T947 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\'
[junit4] 2> 254362 T947 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 254373 T947 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 254374 T947 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 254376 T947 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 254382 T947 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 254383 T947 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 254383 T947 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 254383 T947 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 254384 T947 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 254384 T947 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index/
[junit4] 2> 254384 T947 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 254384 T947 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index
[junit4] 2> 254385 T947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_1,generation=1}
[junit4] 2> 254385 T947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 254386 T947 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 254387 T947 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 254387 T947 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 254387 T947 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 254387 T947 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 254387 T947 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 254388 T947 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 254389 T947 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 254390 T947 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 254391 T947 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 254391 T947 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_1,generation=1}
[junit4] 2> 254391 T947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 254391 T947 oass.SolrIndexSearcher.<init> Opening Searcher@6295eaac main
[junit4] 2> 254392 T947 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 254392 T947 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 254392 T947 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 254392 T948 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6295eaac main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 254393 T910 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 254393 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 254394 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C340 name=collection1 org.apache.solr.core.SolrCore@46e044a3
[junit4] 2> 254409 T944 C340 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 254410 T944 C340 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 254413 T946 C340 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 254415 T946 C340 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_2,generation=2}
[junit4] 2> 254415 T946 C340 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 254416 T946 C340 oass.SolrIndexSearcher.<init> Opening Searcher@3749022e main
[junit4] 2> 254416 T946 C340 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 254417 T948 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3749022e main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 254418 T946 C340 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 2> 254429 T945 C340 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=9
[junit4] 2> 254431 T910 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 254434 T910 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64723
[junit4] 2> 254434 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 254435 T910 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 254435 T910 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave
[junit4] 2> 254435 T910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\'
[junit4] 2> 254455 T910 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\solr.xml
[junit4] 2> 254455 T910 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\solr.xml does not exist, using default configuration
[junit4] 2> 254466 T910 oasc.CoreContainer.<init> New CoreContainer 1403101820
[junit4] 2> 254467 T910 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\]
[junit4] 2> 254468 T910 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 254468 T910 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 254469 T910 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 254469 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 254469 T910 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 254469 T910 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 254470 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 254470 T910 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 254470 T910 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 254470 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 254478 T961 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1
[junit4] 2> 254478 T961 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\'
[junit4] 2> 254510 T961 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 254519 T961 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 254519 T961 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 254523 T961 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 254525 T961 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 254525 T961 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 254526 T961 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\
[junit4] 2> 254526 T961 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 254526 T961 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\
[junit4] 2> 254527 T961 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index/
[junit4] 2> 254527 T961 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 254527 T961 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index
[junit4] 2> 254527 T961 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@511f218f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2808b803),segFN=segments_1,generation=1}
[junit4] 2> 254531 T961 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 254532 T961 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 254532 T961 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 254532 T961 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 254533 T961 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 254533 T961 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 254533 T961 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 254533 T961 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 254535 T961 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 254536 T961 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 254536 T961 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 254536 T961 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@511f218f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2808b803),segFN=segments_1,generation=1}
[junit4] 2> 254537 T961 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 254537 T961 oass.SolrIndexSearcher.<init> Opening Searcher@231275e2 main
[junit4] 2> 254538 T961 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 254538 T961 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 254538 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@231275e2 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 254538 T910 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 254538 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 254539 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C341 name=collection1 org.apache.solr.core.SolrCore@7a251695
[junit4] 2> 254546 T960 C341 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C342 name=collection1 org.apache.solr.core.SolrCore@46e044a3
[junit4] 2> 254549 T941 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 254553 T942 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 254557 T940 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1
[junit4] 2> 254557 T940 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\'
[junit4] 2> 254588 T940 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 254599 T940 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 254600 T940 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 254603 T940 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 254606 T940 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 254606 T940 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 254606 T940 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 254606 T940 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index/
[junit4] 2> 254607 T940 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 254607 T940 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 254608 T940 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 254608 T940 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 254608 T940 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 254608 T940 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 254609 T940 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 254610 T940 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 254615 T940 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 254615 T940 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 254616 T940 oass.SolrIndexSearcher.<init> Opening Searcher@2fcf1e5c main
[junit4] 2> 254616 T940 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 254616 T940 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 254617 T940 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 254617 T940 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 254617 T940 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 254617 T965 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2fcf1e5c main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 254618 T940 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_2,generation=2}
[junit4] 2> 254618 T940 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 254618 T940 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 254619 T940 oass.SolrIndexSearcher.<init> Opening Searcher@4259887b main
[junit4] 2> 254620 T965 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4259887b main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 254621 T940 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 254621 T940 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@46e044a3
[junit4] 2> 254621 T940 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 254622 T940 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 254623 T940 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=66
[junit4] 2> ASYNC NEW_CORE C343 name=collection1 org.apache.solr.core.SolrCore@43f817dc
[junit4] 2> 254626 T938 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 254629 T944 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 254632 T946 C343 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 254636 T945 C343 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 254638 T941 C343 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 254643 T941 C343 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@64cdf1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53c62de4),segFN=segments_3,generation=3}
[junit4] 2> 254643 T941 C343 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 254646 T941 C343 oass.SolrIndexSearcher.<init> Opening Searcher@52fd12c5 main
[junit4] 2> 254646 T941 C343 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 254646 T965 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52fd12c5 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):c2)}
[junit4] 2> 254647 T941 C343 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 2> 254650 T942 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C344 name=collection1 org.apache.solr.core.SolrCore@7a251695
[junit4] 2> 254654 T957 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 254655 T910 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 254760 T958 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 254761 T910 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 254867 T956 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 254868 T910 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 254999 T952 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=28
[junit4] 2> 255000 T910 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 255104 T955 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 255104 T910 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 255197 T938 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 255199 T963 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 255199 T963 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 255199 T963 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 255207 T960 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 255207 T910 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 255216 T944 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=14
[junit4] 2> 255217 T963 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 255219 T963 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index.20130816113306023
[junit4] 2> 255219 T963 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ab408d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6537ceba) fullCopy=false
[junit4] 2> 255223 T946 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C345 name=collection1 org.apache.solr.core.SolrCore@43f817dc
[junit4] 2> 255244 T945 C345 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=3
[junit4] 2> 255247 T941 C345 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 255250 T942 C345 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 255252 T963 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 255253 T963 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 255253 T963 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 255254 T963 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 255255 T963 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 255256 T963 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@511f218f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2808b803),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@511f218f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2808b803),segFN=segments_3,generation=3}
[junit4] 2> 255256 T963 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 255256 T963 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 255257 T963 oass.SolrIndexSearcher.<init> Opening Searcher@23feec7 main
[junit4] 2> 255257 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23feec7 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):c2)}
[junit4] 2> 255258 T963 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index.20130816113306023 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index.20130816113306023;done=true>>]
[junit4] 2> 255258 T963 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index.20130816113306023
[junit4] 2> ASYNC NEW_CORE C346 name=collection1 org.apache.solr.core.SolrCore@7a251695
[junit4] 2> 255313 T957 C346 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 255315 T910 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 255327 T910 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1072752612
[junit4] 2> 255329 T910 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@43f817dc
[junit4] 2> 255329 T910 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 255331 T910 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 255331 T910 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 255331 T910 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 255333 T910 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 255334 T910 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 255334 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index;done=false>>]
[junit4] 2> 255335 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\index
[junit4] 2> 255335 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\;done=false>>]
[junit4] 2> 255335 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984598\master\collection1\data\
[junit4] 2> 255335 T910 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 255402 T910 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1403101820
[junit4] 2> 255404 T910 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7a251695
[junit4] 2> 255405 T910 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 255406 T910 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 255407 T910 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 255408 T910 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 255412 T910 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 255413 T910 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 255414 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index;done=false>>]
[junit4] 2> 255415 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\index
[junit4] 2> 255416 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\;done=false>>]
[junit4] 2> 255416 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641984806\slave\collection1\data\
[junit4] 2> 255417 T910 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 255515 T910 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4] 2> 255535 T910 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 255538 T910 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64728
[junit4] 2> 255539 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 255539 T910 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 255539 T910 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master
[junit4] 2> 255539 T910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\'
[junit4] 2> 255568 T910 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\solr.xml
[junit4] 2> 255569 T910 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\solr.xml does not exist, using default configuration
[junit4] 2> 255591 T910 oasc.CoreContainer.<init> New CoreContainer 1364547793
[junit4] 2> 255592 T910 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\]
[junit4] 2> 255593 T910 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 255593 T910 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 255594 T910 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 255594 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 255594 T910 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 255594 T910 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 255595 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 255595 T910 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 255595 T910 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 255595 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 255608 T976 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1
[junit4] 2> 255608 T976 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\'
[junit4] 2> 255631 T976 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 255642 T976 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 255642 T976 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 255646 T976 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 255652 T976 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 255652 T976 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 255652 T976 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\data\
[junit4] 2> 255652 T976 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 255652 T976 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\data\
[junit4] 2> 255653 T976 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\data\index/
[junit4] 2> 255653 T976 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 255653 T976 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986321\master\collection1\data\index
[junit4] 2> 255654 T976 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@799850ce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@309d035),segFN=segments_1,generation=1}
[junit4] 2> 255654 T976 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 255655 T976 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 255655 T976 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 255655 T976 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 255656 T976 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 255656 T976 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 255656 T976 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 255656 T976 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 255662 T976 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 255663 T976 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 255663 T976 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 255663 T976 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@799850ce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@309d035),segFN=segments_1,generation=1}
[junit4] 2> 255664 T976 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 255664 T976 oass.SolrIndexSearcher.<init> Opening Searcher@3c604ffb main
[junit4] 2> 255664 T976 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 255664 T976 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 255664 T976 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 255665 T977 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c604ffb main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 255666 T910 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 255666 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 255666 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 255685 T910 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 255688 T910 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64731
[junit4] 2> 255690 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 255690 T910 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 255690 T910 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave
[junit4] 2> 255690 T910 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\'
[junit4] 2> 255730 T910 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\solr.xml
[junit4] 2> 255731 T910 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\solr.xml does not exist, using default configuration
[junit4] 2> 255738 T910 oasc.CoreContainer.<init> New CoreContainer 1939646101
[junit4] 2> 255738 T910 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\]
[junit4] 2> 255740 T910 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 255740 T910 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 255740 T910 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 255740 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 255740 T910 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 255740 T910 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 255741 T910 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 255741 T910 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 255741 T910 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 255741 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 255755 T990 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1
[junit4] 2> 255755 T990 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\'
[junit4] 2> 255786 T990 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 255792 T990 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 255792 T990 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 255796 T990 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 255803 T990 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 255803 T990 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 255803 T990 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\
[junit4] 2> 255804 T990 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 255804 T990 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\
[junit4] 2> 255804 T990 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index/
[junit4] 2> 255805 T990 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 255805 T990 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index
[junit4] 2> 255806 T990 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7bf6a998 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14529cc3),segFN=segments_1,generation=1}
[junit4] 2> 255806 T990 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 255807 T990 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 255807 T990 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 255807 T990 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 255807 T990 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 255807 T990 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 255808 T990 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 255808 T990 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 255810 T990 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 255810 T990 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 255810 T990 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 255812 T990 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7bf6a998 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14529cc3),segFN=segments_1,generation=1}
[junit4] 2> 255812 T990 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 255812 T990 oass.SolrIndexSearcher.<init> Opening Searcher@4cc75324 main
[junit4] 2> 255813 T990 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 255813 T990 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 255813 T991 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4cc75324 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 255813 T910 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 255813 T910 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 255814 T910 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C347 name=collection1 org.apache.solr.core.SolrCore@7ef30c98
[junit4] 2> 255820 T970 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 255823 T971 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 255828 T972 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C348 name=collection1 org.apache.solr.core.SolrCore@16b56efe
[junit4] 2> 255830 T987 C348 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 255834 T973 C347 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 255836 T974 C347 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 255839 T974 C347 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@799850ce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@309d035),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@799850ce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@309d035),segFN=segments_2,generation=2}
[junit4] 2> 255839 T974 C347 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 255841 T974 C347 oass.SolrIndexSearcher.<init> Opening Searcher@309ecf82 main
[junit4] 2> 255841 T974 C347 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 255841 T977 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@309ecf82 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c1)}
[junit4] 2> 255842 T974 C347 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 2> 255847 T994 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 255847 T994 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 255849 T975 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 255850 T994 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 255850 T994 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 255850 T994 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 255854 T970 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=2
[junit4] 2> 255855 T994 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 255856 T994 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index.20130816113306662
[junit4] 2> 255856 T994 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@687eb7f2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57c5649) fullCopy=false
[junit4] 2> 255861 T971 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=3
[junit4] 2> 255867 T972 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=2
[junit4] 2> 255870 T973 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 255874 T974 C347 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 255875 T994 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 255876 T994 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 255876 T994 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 255876 T994 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 255877 T994 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 255877 T994 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7bf6a998 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14529cc3),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7bf6a998 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14529cc3),segFN=segments_2,generation=2}
[junit4] 2> 255877 T994 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 255877 T994 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 255878 T994 oass.SolrIndexSearcher.<init> Opening Searcher@10144686 main
[junit4] 2> 255879 T991 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10144686 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c1)}
[junit4] 2> 255879 T994 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index.20130816113306662 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index.20130816113306662;done=true>>]
[junit4] 2> 255879 T994 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376641986477\slave\collection1\data\index.20130816113306662
[junit4] 2> 255880 T985 C348 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:64728/solr/replication&command=fetchindex&wait=true} status=0 QTime=35
[junit4] 2> ASYNC NEW_CORE C349 name=collection1 org.apache.solr.core.SolrCore@7ef30c98
[junit4] 2> 255894 T975 C349 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C350 name=collection1 org.apache.solr.core.SolrCore@16b56efe
[junit4] 2> 255895 T988 C350 oasc.SolrCore.execute [
[...truncated too long message...]
ndler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\index.20130816113419398 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\index.20130816113419398;done=true>>]
[junit4] 2> 328627 T1626 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\index.20130816113419398
[junit4] 2> ASYNC NEW_CORE C459 name=collection1 org.apache.solr.core.SolrCore@7372467c
[junit4] 2> 328675 T1621 C459 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=493 status=0 QTime=0
[junit4] 2> 328676 T910 oas.SolrTestCaseJ4.tearDown ###Ending doTestStopPoll
[junit4] 2> 328688 T910 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1567465331
[junit4] 2> 328690 T910 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@289a4246
[junit4] 2> 328690 T910 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=493,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 328690 T910 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 328691 T910 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 328691 T910 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 328693 T910 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 328693 T910 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 328693 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053263\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053263\master\collection1\data\index;done=false>>]
[junit4] 2> 328694 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053263\master\collection1\data\index
[junit4] 2> 328694 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053263\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053263\master\collection1\data\;done=false>>]
[junit4] 2> 328694 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053263\master\collection1\data\
[junit4] 2> 328696 T910 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 328811 T910 oejut.QueuedThreadPool.doStop WARN 7 threads could not be stopped
[junit4] 2> 328824 T910 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1861243592
[junit4] 2> 328825 T910 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7372467c
[junit4] 2> 328825 T910 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 328826 T910 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 328826 T910 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 328827 T910 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 328827 T910 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 328827 T910 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 328827 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\;done=false>>]
[junit4] 2> 328828 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\
[junit4] 2> 328828 T910 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\index;done=false>>]
[junit4] 2> 328828 T910 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376642053410\slave\collection1\data\index
[junit4] 2> 328829 T910 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 328908 T910 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {newname=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=Memory doPackFST= true), name=PostingsFormat(name=Memory doPackFST= true)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ru_RU, timezone=Asia/Bahrain
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=26674144,total=109477888
[junit4] 2> NOTE: All tests run in this JVM: [HdfsUnloadDistributedZkTest, TestFastOutputStream, TestSolrDeletionPolicy2, DirectUpdateHandlerTest, ClusterStateTest, SolrRequestParserTest, TestComponentsName, OpenExchangeRatesOrgProviderTest, TestQueryTypes, TestNumberUtils, TestSolrJ, SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases, TestSolrQueryParserDefaultOperatorResource, TestMultiCoreConfBootstrap, JSONWriterTest, TestPostingsSolrHighlighter, CoreAdminCreateDiscoverTest, NoCacheHeaderTest, ShowFileRequestHandlerTest, FileBasedSpellCheckerTest, IndexSchemaTest, CoreMergeIndexesAdminHandlerTest, BlockCacheTest, DirectSolrConnectionTest, TestCopyFieldCollectionResource, CircularListTest, TestQuerySenderListener, TestDefaultSearchFieldResource, TestFiltering, TestRecoveryHdfs, TestSort, OverseerCollectionProcessorTest, HighlighterTest, XmlUpdateRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestFuzzyAnalyzedSuggestions, ChaosMonkeySafeLeaderTest, UpdateRequestProcessorFactoryTest, FieldMutatingUpdateProcessorTest, DeleteShardTest, SolrPluginUtilsTest, TestAnalyzedSuggestions, SpellingQueryConverterTest, BasicDistributedZk2Test, RecoveryZkTest, TestRandomMergePolicy, SliceStateTest, TestClassNameShortening, SpellCheckComponentTest, SliceStateUpdateTest, DocValuesMultiTest, TestReplicationHandler]
[junit4] Completed in 75.15s, 14 tests, 1 failure <<< FAILURES!
[...truncated 827 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:389: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:369: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:450: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 320 suites, 1361 tests, 1 failure, 43 ignored (11 assumptions)
Total time: 60 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b102 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure