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/09 05:55:21 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0-ea-b99) - Build
# 3127 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3127/
Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Fri Aug 09 06:41:15 EEST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Fri Aug 09 06:41:15 EEST 2013
at __randomizedtesting.SeedInfo.seed([A3A9185237551815:78021894327D71A6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1507)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:811)
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 9830 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 1592725 T4955 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4] 2> 1592742 T4955 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1592757 T4955 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52900
[junit4] 2> 1592758 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1592758 T4955 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1592758 T4955 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master
[junit4] 2> 1592758 T4955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\'
[junit4] 2> 1592791 T4955 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-1376019654876\master\solr.xml
[junit4] 2> 1592792 T4955 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\solr.xml does not exist, using default configuration
[junit4] 2> 1592803 T4955 oasc.CoreContainer.<init> New CoreContainer 1126596247
[junit4] 2> 1592803 T4955 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\]
[junit4] 2> 1592804 T4955 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1592804 T4955 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1592804 T4955 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1592804 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1592805 T4955 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1592805 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1592805 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1592805 T4955 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1592806 T4955 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1592806 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1592819 T4965 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\collection1
[junit4] 2> 1592819 T4965 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\collection1\'
[junit4] 2> 1592840 T4965 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1592847 T4965 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1592847 T4965 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1592851 T4965 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1592855 T4965 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1592855 T4965 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1592856 T4965 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019654876\master\collection1\data\
[junit4] 2> 1592856 T4965 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1592857 T4965 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-1376019654876\master\collection1\data\
[junit4] 2> 1592857 T4965 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-1376019654876\master\collection1\data\index/
[junit4] 2> 1592857 T4965 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-1376019654876\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1592858 T4965 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-1376019654876\master\collection1\data\index
[junit4] 2> 1592858 T4965 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_1,generation=1}
[junit4] 2> 1592858 T4965 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1592860 T4965 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1592860 T4965 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1592860 T4965 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1592861 T4965 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1592861 T4965 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1592861 T4965 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1592868 T4965 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1592869 T4965 oass.SolrIndexSearcher.<init> Opening Searcher@247f3af0 main
[junit4] 2> 1592869 T4965 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1592869 T4965 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1592870 T4965 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1592870 T4965 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1592870 T4965 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1592870 T4966 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@247f3af0 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1592871 T4955 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1592871 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1592871 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1592888 T4955 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1592893 T4955 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52903
[junit4] 2> 1592894 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1592894 T4955 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1592895 T4955 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave
[junit4] 2> 1592895 T4955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\'
[junit4] 2> 1592912 T4955 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-1376019655026\slave\solr.xml
[junit4] 2> 1592915 T4955 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\solr.xml does not exist, using default configuration
[junit4] 2> 1592922 T4955 oasc.CoreContainer.<init> New CoreContainer 2093757854
[junit4] 2> 1592922 T4955 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\]
[junit4] 2> 1592923 T4955 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1592923 T4955 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1592924 T4955 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1592924 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1592924 T4955 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1592924 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1592925 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1592925 T4955 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1592925 T4955 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1592925 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1592933 T4977 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1
[junit4] 2> 1592933 T4977 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1\'
[junit4] 2> 1592961 T4977 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1592968 T4977 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1592969 T4977 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1592972 T4977 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1592975 T4977 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1592975 T4977 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1592975 T4977 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1\data\
[junit4] 2> 1592976 T4977 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1592976 T4977 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-1376019655026\slave\collection1\data\
[junit4] 2> 1592976 T4977 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-1376019655026\slave\collection1\data\index/
[junit4] 2> 1592977 T4977 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-1376019655026\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1592977 T4977 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-1376019655026\slave\collection1\data\index
[junit4] 2> 1592978 T4977 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_1,generation=1}
[junit4] 2> 1592978 T4977 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1592979 T4977 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1592979 T4977 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1592979 T4977 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1592979 T4977 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1592980 T4977 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1592980 T4977 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1592982 T4977 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1592983 T4977 oass.SolrIndexSearcher.<init> Opening Searcher@357d577e main
[junit4] 2> 1592983 T4977 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1592983 T4977 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1592989 T4977 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 1592989 T4977 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1592989 T4978 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@357d577e main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1592990 T4955 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1592990 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1592990 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C1782 name=collection1 org.apache.solr.core.SolrCore@55b2f342
[junit4] 2> 1593007 T4960 C1782 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=10
[junit4] 2> 1593012 T4959 C1782 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 1593017 T4961 C1782 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 C1783 name=collection1 org.apache.solr.core.SolrCore@7834d21c
[junit4] 2> 1593017 T4971 C1783 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4] 2> 1593021 T4962 C1782 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_1,generation=1}
[junit4] 2> 1593022 T4962 C1782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> ASYNC NEW_CORE C1784 name=collection1 org.apache.solr.core.SolrCore@55b2f342
[junit4] 2> 1593037 T4962 C1784 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 17
[junit4] 2> 1593040 T4963 C1784 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1593042 T4963 C1784 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_2,generation=2}
[junit4] 2> 1593042 T4963 C1784 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1593044 T4963 C1784 oass.SolrIndexSearcher.<init> Opening Searcher@7233b0d7 main
[junit4] 2> 1593044 T4963 C1784 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1593044 T4966 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7233b0d7 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c1)}
[junit4] 2> 1593045 T4963 C1784 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 2> 1593049 T4981 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1593049 T4981 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1593051 T4964 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1593053 T4981 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 1593053 T4981 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 1593053 T4981 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 1593062 T4960 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=6
[junit4] 2> 1593062 T4981 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 1593063 T4981 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-1376019655026\slave\collection1\data\index.20130809064055212
[junit4] 2> 1593063 T4981 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@75580009 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30f62a05) fullCopy=false
[junit4] 2> 1593065 T4959 C1784 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=0
[junit4] 2> 1593068 T4961 C1784 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=0
[junit4] 2> 1593072 T4962 C1784 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> 1593075 T4963 C1784 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> 1593076 T4981 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 1593078 T4981 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 1593078 T4981 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1593078 T4981 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 1593079 T4981 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_2,generation=2}
[junit4] 2> 1593079 T4981 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1593079 T4981 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1593133 T4981 oass.SolrIndexSearcher.<init> Opening Searcher@6c2a3bc8 main
[junit4] 2> 1593134 T4978 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c2a3bc8 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c1)}
[junit4] 2> 1593134 T4981 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-1376019655026\slave\collection1\data\index.20130809064055212 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1\data\index.20130809064055212;done=true>>]
[junit4] 2> 1593134 T4981 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-1376019655026\slave\collection1\data\index.20130809064055212
[junit4] 2> ASYNC NEW_CORE C1785 name=collection1 org.apache.solr.core.SolrCore@7834d21c
[junit4] 2> 1593134 T4972 C1785 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:52900/solr/replication&command=fetchindex&wait=true} status=0 QTime=86
[junit4] 2> 1593141 T4964 C1784 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> 1593142 T4973 C1785 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> 1593145 T4960 C1784 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 1593148 T4959 C1784 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1593152 T4959 C1784 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_3,generation=3}
[junit4] 2> 1593152 T4959 C1784 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1593153 T4959 C1784 oass.SolrIndexSearcher.<init> Opening Searcher@1d00c24e main
[junit4] 2> 1593154 T4959 C1784 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1593154 T4966 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d00c24e main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):c1 _1(5.0):c1)}
[junit4] 2> 1593155 T4959 C1784 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 2> 1593156 T4984 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1593156 T4984 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1593159 T4962 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1593159 T4984 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 1593160 T4984 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 1593160 T4984 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 1593163 T4963 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1
[junit4] 2> 1593163 T4984 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 7
[junit4] 2> 1593164 T4984 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-1376019655026\slave\collection1\data\index.20130809064055314
[junit4] 2> 1593164 T4984 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1926b084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4587aca8) fullCopy=false
[junit4] 2> 1593164 T4984 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfs because it already exists
[junit4] 2> 1593165 T4984 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfe because it already exists
[junit4] 2> 1593167 T4964 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 1593168 T4984 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 1593170 T4960 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 1593173 T4961 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 1593176 T4959 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 1593177 T4984 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 1593180 T4984 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 1593180 T4984 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1593180 T4984 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 1593182 T4984 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 1593183 T4984 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_3,generation=3}
[junit4] 2> 1593183 T4984 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1593183 T4984 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1593184 T4984 oass.SolrIndexSearcher.<init> Opening Searcher@1819e0a3 main
[junit4] 2> 1593185 T4978 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1819e0a3 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):c1 _1(5.0):c1)}
[junit4] 2> 1593185 T4984 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-1376019655026\slave\collection1\data\index.20130809064055314 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1\data\index.20130809064055314;done=true>>]
[junit4] 2> 1593185 T4984 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-1376019655026\slave\collection1\data\index.20130809064055314
[junit4] 2> 1593186 T4974 C1785 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:52900/solr/replication&command=fetchindex&wait=true} status=0 QTime=30
[junit4] 2> 1593192 T4962 C1784 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> 1593193 T4975 C1785 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> 1593196 T4963 C1784 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 1593199 T4964 C1784 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1593201 T4964 C1784 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e247ad4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@285714e3),segFN=segments_4,generation=4}
[junit4] 2> 1593201 T4964 C1784 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 1593202 T4964 C1784 oass.SolrIndexSearcher.<init> Opening Searcher@16c45455 main
[junit4] 2> 1593203 T4964 C1784 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1593203 T4966 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16c45455 main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1)}
[junit4] 2> 1593203 T4964 C1784 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 1593205 T4986 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1593205 T4986 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1593207 T4960 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1593208 T4986 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4] 2> 1593208 T4986 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 1593208 T4986 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 1593211 T4961 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=0
[junit4] 2> 1593212 T4986 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 1593213 T4986 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-1376019655026\slave\collection1\data\index.20130809064055362
[junit4] 2> 1593213 T4986 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@5309300 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@742a0aa8) fullCopy=false
[junit4] 2> 1593215 T4959 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 1593217 T4986 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfs because it already exists
[junit4] 2> 1593217 T4986 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfe because it already exists
[junit4] 2> 1593217 T4986 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4] 2> 1593217 T4986 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 1593217 T4986 oash.SnapPuller.downloadIndexFiles Skipping download for _1.cfs because it already exists
[junit4] 2> 1593220 T4962 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 1593223 T4963 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 1593226 T4964 C1784 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 1593227 T4986 oash.SnapPuller.downloadIndexFiles Skipping download for _1.cfe because it already exists
[junit4] 2> 1593227 T4986 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 1593229 T4986 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 1593229 T4986 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1593229 T4986 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 1593229 T4986 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 1593231 T4986 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12ed317a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46ec1a0f),segFN=segments_4,generation=4}
[junit4] 2> 1593231 T4986 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 1593231 T4986 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1593232 T4986 oass.SolrIndexSearcher.<init> Opening Searcher@604d3c7e main
[junit4] 2> 1593233 T4978 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@604d3c7e main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1)}
[junit4] 2> 1593233 T4986 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-1376019655026\slave\collection1\data\index.20130809064055362 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655026\slave\collection1\data\index.20130809064055362;done=true>>]
[junit4] 2> 1593233 T4986 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-1376019655026\slave\collection1\data\index.20130809064055362
[junit4] 2> 1593233 T4976 C1785 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:52900/solr/replication&command=fetchindex&wait=true} status=0 QTime=29
[junit4] 2> 1593250 T4955 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1593254 T4955 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52910
[junit4] 2> 1593255 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1593255 T4955 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1593256 T4955 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater
[junit4] 2> 1593256 T4955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\'
[junit4] 2> 1593271 T4955 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-1376019655385\repeater\solr.xml
[junit4] 2> 1593273 T4955 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\solr.xml does not exist, using default configuration
[junit4] 2> 1593279 T4955 oasc.CoreContainer.<init> New CoreContainer 1370440641
[junit4] 2> 1593280 T4955 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\]
[junit4] 2> 1593281 T4955 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1593281 T4955 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1593281 T4955 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1593281 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1593281 T4955 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1593281 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1593282 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1593282 T4955 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1593282 T4955 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1593283 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1593291 T4997 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\collection1
[junit4] 2> 1593291 T4997 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\collection1\'
[junit4] 2> 1593332 T4997 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1593339 T4997 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1593340 T4997 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1593343 T4997 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1593347 T4997 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1593347 T4997 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1593347 T4997 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655385\repeater\collection1\data\
[junit4] 2> 1593347 T4997 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1593348 T4997 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-1376019655385\repeater\collection1\data\
[junit4] 2> 1593348 T4997 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-1376019655385\repeater\collection1\data\index/
[junit4] 2> 1593348 T4997 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-1376019655385\repeater\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1593349 T4997 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-1376019655385\repeater\collection1\data\index
[junit4] 2> 1593349 T4997 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3b5daa95 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c0e44f8),segFN=segments_1,generation=1}
[junit4] 2> 1593350 T4997 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1593351 T4997 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1593351 T4997 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1593351 T4997 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1593351 T4997 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1593351 T4997 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1593352 T4997 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1593353 T4997 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1593354 T4997 oass.SolrIndexSearcher.<init> Opening Searcher@13961ae6 main
[junit4] 2> 1593355 T4997 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1593355 T4997 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1593355 T4997 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1593355 T4997 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1593355 T4997 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 1593355 T4997 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1593356 T4997 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1593356 T4998 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13961ae6 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1593357 T4955 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1593357 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1593357 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1593366 T4960 C1784 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 C1786 name=collection1 org.apache.solr.core.SolrCore@790196bc
[junit4] 2> 1593367 T4991 C1786 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> 1593381 T4955 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1370440641
[junit4] 2> 1593382 T4955 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@790196bc
[junit4] 2> 1593384 T4955 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> 1593384 T4955 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1593385 T4955 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1593385 T4955 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1593386 T4955 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1593387 T4955 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1593388 T4955 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-1376019655385\repeater\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-1376019655385\repeater\collection1\data\index;done=false>>]
[junit4] 2> 1593388 T4955 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-1376019655385\repeater\collection1\data\index
[junit4] 2> 1593388 T4955 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-1376019655385\repeater\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-1376019655385\repeater\collection1\data\;done=false>>]
[junit4] 2> 1593389 T4955 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-1376019655385\repeater\collection1\data\
[junit4] 2> 1593390 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1593469 T4955 oas.SolrTestCaseJ4.tearDown ###Ending doTestDetails
[junit4] 2> 1593486 T4955 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1126596247
[junit4] 2> 1593489 T4955 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@55b2f342
[junit4] 2> 1593489 T4955 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1593490 T4955 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1593490 T4955 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1593491 T4955 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1593493 T4955 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1593494 T4955 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1593494 T4955 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-1376019654876\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-1376019654876\master\collection1\data\index;done=false>>]
[junit4] 2> 1593495 T4955 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-1376019654876\master\collection1\data\index
[junit4] 2> 1593496 T4955 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-1376019654876\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-1376019654876\master\collection1\data\;done=false>>]
[junit4] 2> 1593496 T4955 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-1376019654876\master\collection1\data\
[junit4] 2> 1593497 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1593565 T4955 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2093757854
[junit4] 2> 1593567 T4955 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7834d21c
[junit4] 2> 1593569 T4955 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> 1593570 T4955 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1593570 T4955 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1593571 T4955 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1593575 T4955 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1593575 T4955 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1593577 T4955 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-1376019655026\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-1376019655026\slave\collection1\data\;done=false>>]
[junit4] 2> 1593577 T4955 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-1376019655026\slave\collection1\data\
[junit4] 2> 1593578 T4955 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-1376019655026\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-1376019655026\slave\collection1\data\index;done=false>>]
[junit4] 2> 1593578 T4955 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-1376019655026\slave\collection1\data\index
[junit4] 2> 1593580 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1593678 T4955 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 1593692 T4955 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1593696 T4955 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52914
[junit4] 2> 1593697 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1593697 T4955 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1593697 T4955 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master
[junit4] 2> 1593697 T4955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\'
[junit4] 2> 1593731 T4955 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-1376019655829\master\solr.xml
[junit4] 2> 1593732 T4955 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\solr.xml does not exist, using default configuration
[junit4] 2> 1593742 T4955 oasc.CoreContainer.<init> New CoreContainer 882723050
[junit4] 2> 1593743 T4955 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\]
[junit4] 2> 1593743 T4955 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1593744 T4955 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1593744 T4955 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1593744 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1593745 T4955 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1593745 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1593745 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1593745 T4955 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1593745 T4955 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1593745 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1593757 T5009 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1
[junit4] 2> 1593757 T5009 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\'
[junit4] 2> 1593781 T5009 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1593788 T5009 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1593788 T5009 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1593791 T5009 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1593796 T5009 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1593796 T5009 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1593796 T5009 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\data\
[junit4] 2> 1593797 T5009 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1593797 T5009 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-1376019655829\master\collection1\data\
[junit4] 2> 1593797 T5009 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-1376019655829\master\collection1\data\index/
[junit4] 2> 1593797 T5009 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-1376019655829\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1593798 T5009 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-1376019655829\master\collection1\data\index
[junit4] 2> 1593798 T5009 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@54a45aad lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13eee344),segFN=segments_1,generation=1}
[junit4] 2> 1593799 T5009 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1593800 T5009 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1593800 T5009 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1593800 T5009 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1593800 T5009 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1593800 T5009 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1593801 T5009 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1593807 T5009 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1593809 T5009 oass.SolrIndexSearcher.<init> Opening Searcher@38012746 main
[junit4] 2> 1593809 T5009 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1593809 T5009 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1593809 T5009 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1593810 T5009 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1593810 T5009 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1593810 T5010 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38012746 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1593810 T4955 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1593811 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1593811 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1593826 T4955 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1593831 T4955 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52917
[junit4] 2> 1593832 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1593832 T4955 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1593832 T4955 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave
[junit4] 2> 1593832 T4955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\'
[junit4] 2> 1593848 T4955 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-1376019655965\slave\solr.xml
[junit4] 2> 1593850 T4955 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\solr.xml does not exist, using default configuration
[junit4] 2> 1593870 T4955 oasc.CoreContainer.<init> New CoreContainer 1184891658
[junit4] 2> 1593871 T4955 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\]
[junit4] 2> 1593871 T4955 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1593871 T4955 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1593872 T4955 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1593872 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1593872 T4955 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1593872 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1593873 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1593873 T4955 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1593873 T4955 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1593874 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1593881 T5021 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\collection1
[junit4] 2> 1593881 T5021 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\collection1\'
[junit4] 2> 1593911 T5021 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1593921 T5021 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1593921 T5021 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1593926 T5021 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1593929 T5021 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1593929 T5021 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1593929 T5021 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655965\slave\collection1\data\
[junit4] 2> 1593929 T5021 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1593930 T5021 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-1376019655965\slave\collection1\data\
[junit4] 2> 1593930 T5021 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-1376019655965\slave\collection1\data\index/
[junit4] 2> 1593930 T5021 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-1376019655965\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1593930 T5021 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-1376019655965\slave\collection1\data\index
[junit4] 2> 1593931 T5021 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7b7ad28b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f721ac6),segFN=segments_1,generation=1}
[junit4] 2> 1593931 T5021 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1593932 T5021 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1593932 T5021 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1593932 T5021 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1593932 T5021 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1593934 T5021 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1593934 T5021 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1593935 T5021 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1593936 T5021 oass.SolrIndexSearcher.<init> Opening Searcher@2220bebe main
[junit4] 2> 1593936 T5021 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1593936 T5021 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1593937 T5021 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 1593937 T5021 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1593937 T5022 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2220bebe main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1593938 T4955 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1593939 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1593939 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1593958 T4955 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1184891658
[junit4] 2> 1593959 T4955 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@59d3124d
[junit4] 2> 1593960 T4955 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> 1593960 T4955 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1593960 T4955 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1593961 T4955 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1593961 T4955 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1593961 T4955 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1593962 T4955 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-1376019655965\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-1376019655965\slave\collection1\data\index;done=false>>]
[junit4] 2> 1593962 T4955 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-1376019655965\slave\collection1\data\index
[junit4] 2> 1593962 T4955 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-1376019655965\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-1376019655965\slave\collection1\data\;done=false>>]
[junit4] 2> 1593962 T4955 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-1376019655965\slave\collection1\data\
[junit4] 2> 1593964 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> ASYNC NEW_CORE C1787 name=collection1 org.apache.solr.core.SolrCore@6564148a
[junit4] 2> 1594022 T5003 C1787 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1594024 T5003 C1787 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@54a45aad lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13eee344),segFN=segments_1,generation=1}
[junit4] 2> 1594026 T5003 C1787 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1594027 T5003 C1787 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 5
[junit4] 2> 1594031 T5004 C1787 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1594035 T5004 C1787 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@54a45aad lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13eee344),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@54a45aad lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13eee344),segFN=segments_2,generation=2}
[junit4] 2> 1594035 T5004 C1787 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1594036 T5004 C1787 oass.SolrIndexSearcher.<init> Opening Searcher@7e29da8 main
[junit4] 2> 1594036 T5004 C1787 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1594037 T5010 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e29da8 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 1594038 T5004 C1787 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 2> 1594058 T4955 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=882723050
[junit4] 2> 1594061 T4955 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6564148a
[junit4] 2> 1594062 T4955 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> 1594063 T4955 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1594064 T4955 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1594064 T4955 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1594067 T4955 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1594068 T4955 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1594069 T4955 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-1376019655829\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-1376019655829\master\collection1\data\index;done=false>>]
[junit4] 2> 1594070 T4955 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-1376019655829\master\collection1\data\index
[junit4] 2> 1594070 T4955 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-1376019655829\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-1376019655829\master\collection1\data\;done=false>>]
[junit4] 2> 1594071 T4955 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-1376019655829\master\collection1\data\
[junit4] 2> 1594072 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1594128 T4955 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1594136 T4955 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52921
[junit4] 2> 1594138 T4955 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1594140 T4955 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1594141 T4955 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master
[junit4] 2> 1594141 T4955 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\'
[junit4] 2> 1594163 T4955 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-1376019655829\master\solr.xml
[junit4] 2> 1594163 T4955 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\solr.xml does not exist, using default configuration
[junit4] 2> 1594171 T4955 oasc.CoreContainer.<init> New CoreContainer 880085469
[junit4] 2> 1594171 T4955 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\]
[junit4] 2> 1594172 T4955 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1594172 T4955 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1594172 T4955 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1594173 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1594173 T4955 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1594173 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1594174 T4955 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1594174 T4955 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1594174 T4955 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1594175 T4955 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1594181 T5034 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1
[junit4] 2> 1594181 T5034 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\'
[junit4] 2> 1594209 T5034 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1594215 T5034 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1594216 T5034 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1594219 T5034 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1594221 T5034 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1594221 T5034 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 1594222 T5034 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\data\
[junit4] 2> 1594222 T5034 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1594223 T5034 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-1376019655829\master\collection1\data
[junit4] 2> 1594223 T5034 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-1376019655829\master\collection1\data\index/
[junit4] 2> 1594224 T5034 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-1376019655829\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1594224 T5034 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-1376019655829\master\collection1\data\index
[junit4] 2> 1594238 T5034 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019655829\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@28262643; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1594238 T5034 oasc.SolrDeletionPolicy.updateCommits newest commit generati
[...truncated too long message...]
ne-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376019719382\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-1376019719382\master\collection1\data\index;done=false>>]
[junit4] 2> 1664577 T4955 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-1376019719382\master\collection1\data\index
[junit4] 2> 1664579 T4955 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-1376019719382\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-1376019719382\master\collection1\data\;done=false>>]
[junit4] 2> 1664579 T4955 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-1376019719382\master\collection1\data\
[junit4] 2> 1664580 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1664647 T4955 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1154428118
[junit4] 2> 1664647 T4955 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@184d5007
[junit4] 2> 1664648 T4955 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> 1664649 T4955 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1664649 T4955 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1664649 T4955 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1664652 T4955 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1664652 T4955 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1664652 T4955 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-1376019719523\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-1376019719523\slave\collection1\data\index;done=false>>]
[junit4] 2> 1664652 T4955 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-1376019719523\slave\collection1\data\index
[junit4] 2> 1664654 T4955 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-1376019719523\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-1376019719523\slave\collection1\data\;done=false>>]
[junit4] 2> 1664654 T4955 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-1376019719523\slave\collection1\data\
[junit4] 2> 1664654 T4955 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1664737 T4955 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {newname=MockFixedIntBlock(blockSize=1595), id=MockFixedIntBlock(blockSize=1595), name=MockFixedIntBlock(blockSize=1595)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ar_QA, timezone=Europe/Riga
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=111116456,total=338165760
[junit4] 2> NOTE: All tests run in this JVM: [MoreLikeThisHandlerTest, HardAutoCommitTest, BadIndexSchemaTest, SynonymTokenizerTest, UnloadDistributedZkTest, TestCloudManagedSchemaAddField, IndexBasedSpellCheckerTest, TestDistributedGrouping, TestPartialUpdateDeduplication, TestSweetSpotSimilarityFactory, ScriptEngineTest, CSVRequestHandlerTest, MinimalSchemaTest, URLClassifyProcessorTest, TestMultiCoreConfBootstrap, ResourceLoaderTest, TermVectorComponentDistributedTest, DocumentBuilderTest, TestSurroundQueryParser, DistributedQueryElevationComponentTest, SliceStateTest, OverseerCollectionProcessorTest, SuggesterTSTTest, TestStressRecovery, TestSolr4Spatial, XmlUpdateRequestHandlerTest, TestIndexSearcher, TestLuceneMatchVersion, OverseerTest, TestWriterPerf, SpellCheckComponentTest, TestTrie, ZkControllerTest, SuggesterTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrQueryParserResource, TestPseudoReturnFields, SolrTestCaseJ4Test, NotRequiredUniqueKeyTest, FileUtilsTest, TestQueryUtils, TestFuzzyAnalyzedSuggestions, FieldAnalysisRequestHandlerTest, SolrPluginUtilsTest, TestUniqueKeyFieldResource, TestClassNameShortening, WordBreakSolrSpellCheckerTest, SearchHandlerTest, TestComponentsName, TestSolrIndexConfig, TestLazyCores, UpdateRequestProcessorFactoryTest, TestRemoteStreaming, UpdateParamsTest, TestCopyFieldCollectionResource, LoggingHandlerTest, TestLFUCache, ChaosMonkeyNothingIsSafeTest, TestRandomMergePolicy, DefaultValueUpdateProcessorTest, InfoHandlerTest, CurrencyFieldOpenExchangeTest, TestSort, PolyFieldTest, TestBadConfig, SpellingQueryConverterTest, TestAnalyzedSuggestions, IndexSchemaTest, HighlighterConfigTest, CacheHeaderTest, TestAddFieldRealTimeGet, SpatialFilterTest, ShardRoutingTest, TestMaxScoreQueryParser, TestPHPSerializedResponseWriter, ClusterStateUpdateTest, TestStressReorder, SolrRequestParserTest, HdfsDirectoryTest, CollectionsAPIDistributedZkTest, TestIBSimilarityFactory, SolrIndexConfigTest, HighlighterTest, TestDefaultSimilarityFactory, LeaderElectionTest, AutoCommitTest, PreAnalyzedFieldTest, SOLR749Test, DistributedSpellCheckComponentTest, ZkNodePropsTest, TestSchemaVersionResource, HdfsLockFactoryTest, QueryResultKeyTest, TestRandomFaceting, TestFaceting, DebugComponentTest, SliceStateUpdateTest, TestInfoStreamLogging, TestUtils, TestDefaultSearchFieldResource, TestDynamicFieldCollectionResource, TestLMDirichletSimilarityFactory, PreAnalyzedUpdateProcessorTest, TestZkChroot, BinaryUpdateRequestHandlerTest, RegexBoostProcessorTest, TestSolrCoreProperties, BasicFunctionalityTest, SampleTest, TestLRUCache, TestStressLucene, HdfsRecoveryZkTest, TermVectorComponentTest, TestPhraseSuggestions, TestLMJelinekMercerSimilarityFactory, BlockDirectoryTest, TestSchemaNameResource, TestIndexingPerformance, TestSerializedLuceneMatchVersion, LukeRequestHandlerTest, QueryEqualityTest, ShardSplitTest, AssignTest, TestCSVLoader, TermsComponentTest, TestDistributedSearch, TestJoin, TestFunctionQuery, TestCloudManagedSchema, DeleteShardTest, TestSuggestSpellingConverter, TestBinaryResponseWriter, TestSystemIdResolver, ZkCLITest, TestWordDelimiterFilterFactory, TestRTGBase, TestSchemaSimilarityResource, TestSolrJ, OpenExchangeRatesOrgProviderTest, IndexSchemaRuntimeFieldTest, PluginInfoTest, LeaderElectionIntegrationTest, TestSolrDeletionPolicy2, PrimUtilsTest, DirectUpdateHandlerOptimizeTest, XsltUpdateRequestHandlerTest, AnalysisAfterCoreReloadTest, TestReloadAndDeleteDocs, TestFieldResource, QueryElevationComponentTest, RecoveryZkTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed in 72.04s, 14 tests, 1 failure <<< FAILURES!
[...truncated 472 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:449: 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: 318 suites, 1345 tests, 1 failure, 44 ignored (11 assumptions)
Total time: 63 minutes 59 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_25) - Build #
3128 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3128/
Java: 64bit/jdk1.7.0_25 -XX:+UseCompressedOops -XX:+UseParallelGC
4 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Backup success not detected:<?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><lst name="details"><str name="indexSize">22.38 KB</str><str name="indexPath">C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050224117\master\collection1\data\index/</str><arr name="commits"><lst><long name="indexVersion">1376050226025</long><long name="generation">2</long><arr name="filelist"><str>_0.fdt</str><str>_0.fdx</str><str>_0.fnm</str><str>_0.si</str><str>_0_Lucene40_0.frq</str><str>_0_Lucene40_0.tim</str><str>_0_Lucene40_0.tip</str><str>_0_nrm.cfe</str><str>_0_nrm.cfs</str><str>_1.fdt</str><str>_1.fdx</str><str>_1.fnm</str><str>_1.si</str><str>_1_Lucene40_0.frq</str><str>_1_Lucene40_0.tim</str><str>_1_Lucene40_0.tip</str><str>_1_nrm.cfe</str><str>_1_nrm.cfs</str><str>segments_2</str></arr></lst></arr><str name="isMaster">true</str><str name="isSlave">false</str><long name="indexVersion">1376050226025</long><long name="generation">2</long><lst name="master"><str name="confFiles">schema-replication2.xml:schema.xml</str><arr name="replicateAfter"><str>commit</str></arr><str name="replicationEnabled">true</str><long name="replicableVersion">1376050226025</long><long name="replicableGeneration">2</long></lst><lst name="backup"><str name="startTime">Fri Aug 09 09:10:26 SRT 2013</str><int name="fileCount">19</int><str name="status">success</str><str name="snapshotCompletedAt">Fri Aug 09 09:10:26 SRT 2013</str></lst></lst><str name="WARNING">This response format is experimental. It is likely to change in the future.</str> </response>
Stack Trace:
java.lang.AssertionError: Backup success not detected:<?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><lst name="details"><str name="indexSize">22.38 KB</str><str name="indexPath">C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050224117\master\collection1\data\index/</str><arr name="commits"><lst><long name="indexVersion">1376050226025</long><long name="generation">2</long><arr name="filelist"><str>_0.fdt</str><str>_0.fdx</str><str>_0.fnm</str><str>_0.si</str><str>_0_Lucene40_0.frq</str><str>_0_Lucene40_0.tim</str><str>_0_Lucene40_0.tip</str><str>_0_nrm.cfe</str><str>_0_nrm.cfs</str><str>_1.fdt</str><str>_1.fdx</str><str>_1.fnm</str><str>_1.si</str><str>_1_Lucene40_0.frq</str><str>_1_Lucene40_0.tim</str><str>_1_Lucene40_0.tip</str><str>_1_nrm.cfe</str><str>_1_nrm.cfs</str><str>segments_2</str></arr></lst></arr><str name="isMaster">true</str><str name="isSlave">false</str><long name="indexVersion">1376050226025</long><long name="generation">2</long><lst name="master"><str name="confFiles">schema-replication2.xml:schema.xml</str><arr name="replicateAfter"><str>commit</str></arr><str name="replicationEnabled">true</str><long name="replicableVersion">1376050226025</long><long name="replicableGeneration">2</long></lst><lst name="backup"><str name="startTime">Fri Aug 09 09:10:26 SRT 2013</str><int name="fileCount">19</int><str name="status">success</str><str name="snapshotCompletedAt">Fri Aug 09 09:10:26 SRT 2013</str></lst></lst><str name="WARNING">This response format is experimental. It is likely to change in the future.</str>
</response>
at __randomizedtesting.SeedInfo.seed([D556719D196F7710:94DD51F83ED1845F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1395)
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:606)
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)
REGRESSION: org.apache.solr.handler.admin.ShowFileRequestHandlerTest.testGetRawFile
Error Message:
Server at http://127.0.0.1:63698/solr/collection1 returned non ok status:500, message:{msg=SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent',trace=org.apache.solr.common.SolrException: SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent' at org.apache.solr.core.CoreContainer.getCore(CoreContainer.java:779) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:250) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:158) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.hand
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:63698/solr/collection1 returned non ok status:500, message:{msg=SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent',trace=org.apache.solr.common.SolrException: SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent' at org.apache.solr.core.CoreContainer.getCore(CoreContainer.java:779) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:250) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:158) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.hand
at __randomizedtesting.SeedInfo.seed([D556719D196F7710:B5BD977D3B21329]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.handler.admin.ShowFileRequestHandlerTest.testGetRawFile(ShowFileRequestHandlerTest.java:123)
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:606)
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)
REGRESSION: org.apache.solr.handler.admin.ShowFileRequestHandlerTest.testDirList
Error Message:
Server at http://127.0.0.1:63698/solr/collection1 returned non ok status:500, message:{msg=SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent',trace=org.apache.solr.common.SolrException: SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent' at org.apache.solr.core.CoreContainer.getCore(CoreContainer.java:779) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:250) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:158) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.hand
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:63698/solr/collection1 returned non ok status:500, message:{msg=SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent',trace=org.apache.solr.common.SolrException: SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent' at org.apache.solr.core.CoreContainer.getCore(CoreContainer.java:779) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:250) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:158) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.hand
at __randomizedtesting.SeedInfo.seed([D556719D196F7710:3C6E606FD131ED2A]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
at org.apache.solr.handler.admin.ShowFileRequestHandlerTest.testDirList(ShowFileRequestHandlerTest.java:89)
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:606)
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)
REGRESSION: org.apache.solr.handler.admin.ShowFileRequestHandlerTest.test404ViaHttp
Error Message:
expected:<404> but was:<500>
Stack Trace:
java.lang.AssertionError: expected:<404> but was:<500>
at __randomizedtesting.SeedInfo.seed([D556719D196F7710:9F5C780374363CC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.admin.ShowFileRequestHandlerTest.test404ViaHttp(ShowFileRequestHandlerTest.java:58)
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:606)
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 9820 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 2915501 T5935 oas.SolrTestCaseJ4.setUp ###Starting testEmptyCommits
[junit4] 2> 2915516 T5935 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2915520 T5935 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:62899
[junit4] 2> 2915520 T5935 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2915521 T5935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2915521 T5935 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master
[junit4] 2> 2915521 T5935 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\'
[junit4] 2> 2915548 T5935 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-1376050202838\master\solr.xml
[junit4] 2> 2915549 T5935 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\solr.xml does not exist, using default configuration
[junit4] 2> 2915628 T5935 oasc.CoreContainer.<init> New CoreContainer 1024005400
[junit4] 2> 2915628 T5935 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\]
[junit4] 2> 2915630 T5935 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2915630 T5935 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2915631 T5935 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2915631 T5935 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2915631 T5935 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2915631 T5935 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2915632 T5935 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2915632 T5935 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2915632 T5935 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2915633 T5935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2915648 T5945 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\collection1
[junit4] 2> 2915648 T5945 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\collection1\'
[junit4] 2> 2915698 T5945 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2915751 T5945 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2915752 T5945 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2915757 T5945 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2915778 T5945 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2915779 T5945 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2915780 T5945 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050202838\master\collection1\data\
[junit4] 2> 2915780 T5945 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2915780 T5945 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-1376050202838\master\collection1\data\
[junit4] 2> 2915780 T5945 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-1376050202838\master\collection1\data\index/
[junit4] 2> 2915780 T5945 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-1376050202838\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2915781 T5945 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-1376050202838\master\collection1\data\index
[junit4] 2> 2915781 T5945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_1,generation=1}
[junit4] 2> 2915781 T5945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2915783 T5945 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2915783 T5945 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2915784 T5945 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2915784 T5945 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2915784 T5945 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2915784 T5945 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2915792 T5945 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2915795 T5945 oass.SolrIndexSearcher.<init> Opening Searcher@2ea29180 main
[junit4] 2> 2915796 T5945 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2915796 T5945 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2915796 T5945 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2915796 T5945 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2915797 T5945 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2915797 T5946 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ea29180 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2915797 T5935 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2915797 T5935 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2915798 T5935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2915812 T5935 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2915815 T5935 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:62902
[junit4] 2> 2915816 T5935 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2915817 T5935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2915817 T5935 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave
[junit4] 2> 2915817 T5935 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\'
[junit4] 2> 2915831 T5935 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-1376050203137\slave\solr.xml
[junit4] 2> 2915833 T5935 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\solr.xml does not exist, using default configuration
[junit4] 2> 2915886 T5935 oasc.CoreContainer.<init> New CoreContainer 208954736
[junit4] 2> 2915886 T5935 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\]
[junit4] 2> 2915888 T5935 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2915888 T5935 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2915888 T5935 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2915888 T5935 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2915889 T5935 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2915889 T5935 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2915889 T5935 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2915890 T5935 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2915890 T5935 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2915890 T5935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2915901 T5957 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1
[junit4] 2> 2915901 T5957 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\'
[junit4] 2> 2915961 T5957 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2916015 T5957 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2916016 T5957 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2916022 T5957 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2916042 T5957 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2916043 T5957 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2916044 T5957 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\data\
[junit4] 2> 2916044 T5957 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2916044 T5957 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-1376050203137\slave\collection1\data\
[junit4] 2> 2916044 T5957 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-1376050203137\slave\collection1\data\index/
[junit4] 2> 2916045 T5957 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-1376050203137\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2916045 T5957 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-1376050203137\slave\collection1\data\index
[junit4] 2> 2916045 T5957 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_1,generation=1}
[junit4] 2> 2916045 T5957 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2916047 T5957 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2916047 T5957 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2916047 T5957 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2916047 T5957 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2916048 T5957 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2916048 T5957 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2916050 T5957 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2916054 T5957 oass.SolrIndexSearcher.<init> Opening Searcher@d6de4f4 main
[junit4] 2> 2916054 T5957 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2916054 T5957 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2916055 T5957 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2916055 T5957 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2916055 T5958 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d6de4f4 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2916056 T5935 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2916056 T5935 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2916056 T5935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2028 name=collection1 org.apache.solr.core.SolrCore@5f916936
[junit4] 2> 2916070 T5940 C2028 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> 2916075 T5941 C2028 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_1,generation=1}
[junit4] 2> 2916076 T5941 C2028 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2916076 T5941 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 3
[junit4] 2> 2916079 T5942 C2028 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2916080 T5942 C2028 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_2,generation=2}
[junit4] 2> 2916080 T5942 C2028 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2916082 T5942 C2028 oass.SolrIndexSearcher.<init> Opening Searcher@1d506a35 main
[junit4] 2> 2916082 T5942 C2028 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2916082 T5946 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d506a35 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 2> 2916083 T5942 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 4
[junit4] 2> 2916100 T5961 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2916100 T5961 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2916102 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2916103 T5961 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2916103 T5961 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2916103 T5961 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2916108 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=3
[junit4] 2> 2916109 T5961 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 2916109 T5961 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-1376050203137\slave\collection1\data\index.20130809091003445
[junit4] 2> 2916109 T5961 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b3318b2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f0eb79c) fullCopy=false
[junit4] 2> 2916111 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916124 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916127 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916130 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916132 T5943 C2028 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> 2916135 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916138 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916141 T5940 C2028 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> 2916144 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916148 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2916149 T5961 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2916152 T5961 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2916152 T5961 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2916153 T5961 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2916154 T5961 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_2,generation=2}
[junit4] 2> 2916154 T5961 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2916155 T5961 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2916156 T5961 oass.SolrIndexSearcher.<init> Opening Searcher@418df424 main
[junit4] 2> 2916156 T5958 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@418df424 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 2> 2916157 T5961 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-1376050203137\slave\collection1\data\index.20130809091003445 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\data\index.20130809091003445;done=true>>]
[junit4] 2> 2916157 T5961 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-1376050203137\slave\collection1\data\index.20130809091003445
[junit4] 2> ASYNC NEW_CORE C2029 name=collection1 org.apache.solr.core.SolrCore@5871146
[junit4] 2> 2916157 T5951 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:62899/solr/replication&command=fetchindex&wait=true} status=0 QTime=58
[junit4] 2> 2916163 T5952 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty1&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4] 2> 2916166 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 2916169 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 2916176 T5939 C2028 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> 2916177 T5953 C2029 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> 2916179 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2916182 T5954 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2916185 T5941 C2028 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2916186 T5941 C2028 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 2916186 T5941 C2028 oass.SolrIndexSearcher.<init> Opening Searcher@34d75241 main
[junit4] 2> 2916187 T5941 C2028 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2916187 T5946 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34d75241 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 2> 2916188 T5941 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 3
[junit4] 2> 2916191 T5964 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2916191 T5964 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2916195 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2916196 T5964 oash.SnapPuller.fetchLatestIndex Slave in sync with master.
[junit4] 2> 2916196 T5955 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:62899/solr/replication&command=fetchindex&wait=true} status=0 QTime=6
[junit4] 2> 2916200 T5943 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 2916203 T5944 C2028 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2916207 T5944 C2028 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_3,generation=3}
[junit4] 2> 2916208 T5944 C2028 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2916209 T5944 C2028 oass.SolrIndexSearcher.<init> Opening Searcher@44381c80 main
[junit4] 2> 2916211 T5944 C2028 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2916211 T5946 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44381c80 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 2916212 T5944 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 9
[junit4] 2> 2916214 T5965 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2916214 T5965 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2916218 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2916219 T5965 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 2916219 T5965 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 2916219 T5965 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2916224 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=2
[junit4] 2> 2916226 T5965 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 19
[junit4] 2> 2916226 T5965 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-1376050203137\slave\collection1\data\index.20130809091003562
[junit4] 2> 2916226 T5965 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@185497be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@40dca3a8) fullCopy=false
[junit4] 2> 2916230 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 2916234 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916235 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tim because it already exists
[junit4] 2> 2916236 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 2916238 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916240 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfs because it already exists
[junit4] 2> 2916244 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 2916245 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tip because it already exists
[junit4] 2> 2916247 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916251 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916256 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916257 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.frq because it already exists
[junit4] 2> 2916260 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916263 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916265 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfe because it already exists
[junit4] 2> 2916265 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 2916265 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2916265 T5965 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 2916268 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2916270 T5965 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2916274 T5965 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2916274 T5965 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2916274 T5965 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2916276 T5965 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2916278 T5965 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_3,generation=3}
[junit4] 2> 2916279 T5965 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2916280 T5965 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2916282 T5965 oass.SolrIndexSearcher.<init> Opening Searcher@5aff8019 main
[junit4] 2> 2916282 T5958 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5aff8019 main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 2916283 T5965 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-1376050203137\slave\collection1\data\index.20130809091003562 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\data\index.20130809091003562;done=true>>]
[junit4] 2> 2916284 T5965 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-1376050203137\slave\collection1\data\index.20130809091003562
[junit4] 2> 2916285 T5956 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:62899/solr/replication&command=fetchindex&wait=true} status=0 QTime=72
[junit4] 2> 2916289 T5952 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty2&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 2916293 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 2916296 T5940 C2028 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> 2916303 T5941 C2028 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> 2916304 T5953 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=5
[junit4] 2> 2916307 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2916310 T5954 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2916314 T5943 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4] 2> 2916317 T5944 C2028 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2916318 T5944 C2028 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_4,generation=4}
[junit4] 2> 2916319 T5944 C2028 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2916319 T5944 C2028 oass.SolrIndexSearcher.<init> Opening Searcher@321a6ba3 realtime
[junit4] 2> 2916320 T5944 C2028 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2916320 T5944 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2} {commit=} 0 3
[junit4] 2> 2916323 T5967 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2916323 T5967 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2916325 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2916326 T5967 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4] 2> 2916326 T5967 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 2916326 T5967 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2916329 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=1
[junit4] 2> 2916329 T5967 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 28
[junit4] 2> 2916331 T5967 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-1376050203137\slave\collection1\data\index.20130809091003667
[junit4] 2> 2916331 T5967 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@5540ace6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50d1ee39) fullCopy=false
[junit4] 2> 2916334 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916336 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.tim because it already exists
[junit4] 2> 2916336 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4] 2> 2916336 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.tip because it already exists
[junit4] 2> 2916338 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916341 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916341 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.frq because it already exists
[junit4] 2> 2916342 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2916344 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916347 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916348 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1_nrm.cfs because it already exists
[junit4] 2> 2916348 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tim because it already exists
[junit4] 2> 2916348 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 2916348 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.frq because it already exists
[junit4] 2> 2916350 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916351 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfs because it already exists
[junit4] 2> 2916353 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916354 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1_nrm.cfe because it already exists
[junit4] 2> 2916354 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tip because it already exists
[junit4] 2> 2916356 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916357 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4] 2> 2916357 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4] 2> 2916359 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916360 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4] 2> 2916361 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfe because it already exists
[junit4] 2> 2916361 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 2916361 T5967 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 2916364 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2916364 T5967 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2916366 T5967 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2916367 T5967 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2916367 T5967 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2916368 T5967 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2916370 T5967 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_4,generation=4}
[junit4] 2> 2916370 T5967 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2916371 T5967 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2916373 T5967 oass.SolrIndexSearcher.<init> Opening Searcher@65a66196 main
[junit4] 2> 2916373 T5958 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@65a66196 main{StandardDirectoryReader(segments_4:7:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
[junit4] 2> 2916374 T5967 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-1376050203137\slave\collection1\data\index.20130809091003667 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\data\index.20130809091003667;done=true>>]
[junit4] 2> 2916374 T5967 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-1376050203137\slave\collection1\data\index.20130809091003667
[junit4] 2> 2916375 T5955 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:62899/solr/replication&command=fetchindex&wait=true} status=0 QTime=53
[junit4] 2> 2916378 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty3&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 2916381 T5951 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:empty3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 2916385 T5940 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4] 2> 2916387 T5941 C2028 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2916389 T5941 C2028 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=4
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2d0eaec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31c26cba),segFN=segments_5,generation=5}
[junit4] 2> 2916390 T5941 C2028 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 2916391 T5941 C2028 oass.SolrIndexSearcher.<init> Opening Searcher@2bfb0aa3 main
[junit4] 2> 2916392 T5941 C2028 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2916392 T5946 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2bfb0aa3 main{StandardDirectoryReader(segments_5:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4] 2> 2916393 T5941 C2028 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} {commit=} 0 6
[junit4] 2> 2916396 T5969 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2916396 T5969 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2916399 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2916399 T5969 oash.SnapPuller.fetchLatestIndex Master's generation: 5
[junit4] 2> 2916400 T5969 oash.SnapPuller.fetchLatestIndex Slave's generation: 4
[junit4] 2> 2916400 T5969 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2916403 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=5&version=2} status=0 QTime=1
[junit4] 2> 2916404 T5969 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 37
[junit4] 2> 2916406 T5969 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-1376050203137\slave\collection1\data\index.20130809091003740
[junit4] 2> 2916406 T5969 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@36b75e94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a1ea66f) fullCopy=false
[junit4] 2> 2916406 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2.si because it already exists
[junit4] 2> 2916406 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.tim because it already exists
[junit4] 2> 2916409 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916414 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916418 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916420 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fnm because it already exists
[junit4] 2> 2916420 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.tip because it already exists
[junit4] 2> 2916421 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2_Lucene40_0.tip because it already exists
[junit4] 2> 2916421 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2_nrm.cfe because it already exists
[junit4] 2> 2916424 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_Lucene40_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=1
[junit4] 2> 2916425 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.frq because it already exists
[junit4] 2> 2916425 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2916425 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2_nrm.cfs because it already exists
[junit4] 2> 2916425 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2_Lucene40_0.tim because it already exists
[junit4] 2> 2916426 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1_nrm.cfs because it already exists
[junit4] 2> 2916428 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_Lucene40_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916429 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tim because it already exists
[junit4] 2> 2916431 T5943 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_Lucene40_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916433 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 2916433 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1_Lucene40_0.frq because it already exists
[junit4] 2> 2916433 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2.fdt because it already exists
[junit4] 2> 2916433 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfs because it already exists
[junit4] 2> 2916434 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2.fdx because it already exists
[junit4] 2> 2916434 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1_nrm.cfe because it already exists
[junit4] 2> 2916434 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0_Lucene40_0.tip because it already exists
[junit4] 2> 2916434 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2.fnm because it already exists
[junit4] 2> 2916437 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916439 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdx because it already exists
[junit4] 2> 2916441 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916443 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1.fdt because it already exists
[junit4] 2> 2916443 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _2_Lucene40_0.frq because it already exists
[junit4] 2> 2916443 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4] 2> 2916443 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfe because it already exists
[junit4] 2> 2916444 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 2916446 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916450 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_5&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=5} status=0 QTime=0
[junit4] 2> 2916452 T5969 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> 2916452 T5969 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2916456 T5969 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2916456 T5969 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2916456 T5969 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2916457 T5969 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2916460 T5969 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=4
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@8990be0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2154b033),segFN=segments_5,generation=5}
[junit4] 2> 2916461 T5969 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 2916461 T5969 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2916463 T5969 oass.SolrIndexSearcher.<init> Opening Searcher@2368fc21 main
[junit4] 2> 2916463 T5958 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2368fc21 main{StandardDirectoryReader(segments_5:9:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4] 2> 2916464 T5969 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-1376050203137\slave\collection1\data\index.20130809091003740 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376050203137\slave\collection1\data\index.20130809091003740;done=true>>]
[junit4] 2> 2916464 T5969 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-1376050203137\slave\collection1\data\index.20130809091003740
[junit4] 2> 2916465 T5952 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:62899/solr/replication&command=fetchindex&wait=true} status=0 QTime=70
[junit4] 2> 2916469 T5942 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2} hits=4 status=0 QTime=1
[junit4] 2> 2916474 T5953 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2} hits=4 status=0 QTime=1
[junit4] 2> 2916478 T5944 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 2916483 T5939 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4] 2> 2916498 T5940 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=8
[junit4] 2> 2916498 T5954 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=12
[junit4] 2> 2916501 T5941 C2028 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2916504 T5955 C2029 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2916505 T5935 oas.SolrTestCaseJ4.tearDown ###Ending testEmptyCommits
[junit4] 2> 2916520 T5935 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1024005400
[junit4] 2> 2916522 T5935 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5f916936
[junit4] 2> 2916523 T5935 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2916523 T5935 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2916524 T5935 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2916525 T5935 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2916527 T5935 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2916527 T5935 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2916528 T5935 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-1376050202838\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-1376050202838\master\collection1\data\;done=false>>]
[junit4] 2> 2916528 T5935 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-1376050202838\master\collection1\data\
[junit4] 2> 2916529 T5935 o
[...truncated too long message...]
lassLoader.java:424)
[junit4] 2> at java.net.FactoryURLClassLoader.loadClass(URLClassLoader.java:789)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:357)
[junit4] 2> at java.lang.Class.forName0(Native Method)
[junit4] 2> at java.lang.Class.forName(Class.java:270)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:433)
[junit4] 2> ... 21 more
[junit4] 2>
[junit4] 2> 3372283 T7774 oas.SolrTestCaseJ4.tearDown ###Ending test404ViaHttp
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ShowFileRequestHandlerTest -Dtests.method=test404ViaHttp -Dtests.seed=D556719D196F7710 -Dtests.slow=true -Dtests.locale=fr -Dtests.timezone=America/Belize -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.01s | ShowFileRequestHandlerTest.test404ViaHttp <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<404> but was:<500>
[junit4] > at __randomizedtesting.SeedInfo.seed([D556719D196F7710:9F5C780374363CC]:0)
[junit4] > at org.apache.solr.handler.admin.ShowFileRequestHandlerTest.test404ViaHttp(ShowFileRequestHandlerTest.java:58)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 3372295 T7774 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2072281699
[junit4] 2> 3372295 T7774 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 3372346 T7774 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 3372346 T7774 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=343097579
[junit4] 2> 3372347 T7774 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2f8dce88
[junit4] 2> 3372366 T7774 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> 3372367 T7774 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 3372367 T7774 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 3372368 T7774 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 3372368 T7774 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 3372368 T7774 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3372369 T7774 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShowFileRequestHandlerTest-1376050657290\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShowFileRequestHandlerTest-1376050657290\index;done=false>>]
[junit4] 2> 3372369 T7774 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShowFileRequestHandlerTest-1376050657290\index
[junit4] 2> 3372369 T7774 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShowFileRequestHandlerTest-1376050657290\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShowFileRequestHandlerTest-1376050657290\;done=false>>]
[junit4] 2> 3372370 T7774 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShowFileRequestHandlerTest-1376050657290\
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=fr, timezone=America/Belize
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=2,threads=1,free=212424312,total=401276928
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedSchema, DeleteShardTest, FieldAnalysisRequestHandlerTest, SimplePostToolTest, TestWriterPerf, InfoHandlerTest, PreAnalyzedUpdateProcessorTest, TestSuggestSpellingConverter, QueryParsingTest, DOMUtilTest, TestStressLucene, CopyFieldTest, MinimalSchemaTest, CSVRequestHandlerTest, LoggingHandlerTest, QueryResultKeyTest, PrimitiveFieldTypeTest, MoreLikeThisHandlerTest, DirectSolrConnectionTest, ShardSplitTest, SoftAutoCommitTest, TestSolrXmlPersistence, AnalysisAfterCoreReloadTest, CurrencyFieldXmlFileTest, TestRandomFaceting, TestPerFieldSimilarity, TestComponentsName, SyncSliceTest, TestLMJelinekMercerSimilarityFactory, TestFuzzyAnalyzedSuggestions, BasicFunctionalityTest, SpellCheckCollatorTest, SpellCheckComponentTest, HdfsUnloadDistributedZkTest, RegexBoostProcessorTest, CustomCollectionTest, PolyFieldTest, TestCharFilters, TestQueryTypes, CollectionsAPIDistributedZkTest, ChaosMonkeySafeLeaderTest, RAMDirectoryFactoryTest, UnloadDistributedZkTest, TestBinaryResponseWriter, ChaosMonkeyNothingIsSafeTest, IndexSchemaTest, TestBinaryField, BasicDistributedZk2Test, AutoCommitTest, SynonymTokenizerTest, TestFastOutputStream, TestSchemaSimilarityResource, DocValuesTest, PeerSyncTest, TestSolrXMLSerializer, TestValueSourceCache, TestDistributedSearch, NumericFieldsTest, TestJmxMonitoredMap, TestMaxScoreQueryParser, HighlighterConfigTest, TestSolrQueryParser, TestSolrDeletionPolicy2, TestSolrIndexConfig, TestMergePolicyConfig, SolrIndexSplitterTest, TestRangeQuery, CoreAdminHandlerTest, ExternalFileFieldSortTest, TermVectorComponentDistributedTest, TestPartialUpdateDeduplication, TestSearchPerf, TestSolrQueryParserDefaultOperatorResource, OverseerTest, TestFastLRUCache, TestZkChroot, TestDefaultSimilarityFactory, RequestHandlersTest, TestFastWriter, TestDocSet, TestLazyCores, CoreAdminCreateDiscoverTest, HdfsLockFactoryTest, DistributedQueryElevationComponentTest, DocValuesMultiTest, FastVectorHighlighterTest, TestArbitraryIndexDir, TestShardHandlerFactory, TestInfoStreamLogging, BadCopyFieldTest, ConvertedLegacyTest, SolrInfoMBeanTest, TestRandomDVFaceting, SliceStateTest, TestClassNameShortening, SuggesterTest, IndexBasedSpellCheckerTest, ReturnFieldsTest, TestConfig, DistributedSpellCheckComponentTest, TestSolrQueryParserResource, OpenCloseCoreStressTest, TestStressReorder, TestFunctionQuery, AliasIntegrationTest, TestMultiCoreConfBootstrap, StatelessScriptUpdateProcessorFactoryTest, TestCloudManagedSchemaAddField, PathHierarchyTokenizerFactoryTest, HdfsBasicDistributedZk2Test, SolrRequestParserTest, TestQuerySenderListener, TestRecovery, TestSurroundQueryParser, BadComponentTest, UniqFieldsUpdateProcessorFactoryTest, ParsingFieldUpdateProcessorsTest, XmlUpdateRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, StressHdfsTest, TestSerializedLuceneMatchVersion, TestPostingsSolrHighlighter, TestDocumentBuilder, TestWordDelimiterFilterFactory, TestLuceneMatchVersion, TestCodecSupport, DistributedTermsComponentTest, SuggesterFSTTest, JSONWriterTest, SuggesterWFSTTest, TestCopyFieldCollectionResource, SuggesterTSTTest, FileUtilsTest, TestPseudoReturnFields, SolrPluginUtilsTest, HardAutoCommitTest, UUIDFieldTest, SampleTest, SpellingQueryConverterTest, CurrencyFieldOpenExchangeTest, TestSolrDeletionPolicy1, RecoveryZkTest, SpellPossibilityIteratorTest, CachingDirectoryFactoryTest, TestSort, ResourceLoaderTest, HdfsCollectionsAPIDistributedZkTest, ZkNodePropsTest, BinaryUpdateRequestHandlerTest, OutputWriterTest, AssignTest, TestDFRSimilarityFactory, AlternateDirectoryTest, BasicDistributedZkTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, ZkCLITest, ShardRoutingCustomTest, SliceStateUpdateTest, TestDistributedGrouping, TestFaceting, TestHashPartitioner, ZkControllerTest, TestRealTimeGet, TestJoin, TestReload, TestStressVersions, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, TestFiltering, DirectUpdateHandlerTest, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest]
[junit4] Completed in 2.47s, 4 tests, 1 failure, 2 errors <<< FAILURES!
[...truncated 385 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:449: 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: 318 suites, 1345 tests, 2 errors, 2 failures, 40 ignored (11 assumptions)
Total time: 81 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_25 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure