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/07/25 01:55:15 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0-ea-b99) - Build
# 3071 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3071/
Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Thu Jul 25 07:37:25 HKT 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Thu Jul 25 07:37:25 HKT 2013
at __randomizedtesting.SeedInfo.seed([64180D082B6AC204:BFB30DCE2E42ABB7]: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 9671 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 1237581 T3345 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 1237591 T3345 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1237595 T3345 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61543
[junit4] 2> 1237596 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1237596 T3345 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1237596 T3345 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master
[junit4] 2> 1237596 T3345 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\'
[junit4] 2> 1237622 T3345 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-1374709041704\master\solr.xml
[junit4] 2> 1237624 T3345 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\solr.xml does not exist, using default configuration
[junit4] 2> 1237638 T3345 oasc.CoreContainer.<init> New CoreContainer 1944577124
[junit4] 2> 1237638 T3345 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\]
[junit4] 2> 1237639 T3345 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1237639 T3345 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1237639 T3345 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1237639 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1237640 T3345 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1237640 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1237640 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1237640 T3345 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1237640 T3345 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1237640 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1237649 T3355 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1
[junit4] 2> 1237649 T3355 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1\'
[junit4] 2> 1237665 T3355 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1237669 T3355 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1237669 T3355 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1237672 T3355 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1237676 T3355 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1237676 T3355 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1237676 T3355 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1\data\
[junit4] 2> 1237676 T3355 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1237676 T3355 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-1374709041704\master\collection1\data\
[junit4] 2> 1237676 T3355 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-1374709041704\master\collection1\data\index/
[junit4] 2> 1237676 T3355 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-1374709041704\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1237677 T3355 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-1374709041704\master\collection1\data\index
[junit4] 2> 1237678 T3355 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@542c6045 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@71e1d8dc),segFN=segments_1,generation=1}
[junit4] 2> 1237678 T3355 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1237680 T3355 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1237680 T3355 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1237680 T3355 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1237680 T3355 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1237680 T3355 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1237680 T3355 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1237684 T3355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1237685 T3355 oass.SolrIndexSearcher.<init> Opening Searcher@719183f9 main
[junit4] 2> 1237685 T3355 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1237685 T3355 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1237685 T3355 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1237686 T3355 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1237687 T3356 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@719183f9 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1237687 T3355 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1237687 T3345 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1237687 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1237688 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1237700 T3345 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1237704 T3345 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61546
[junit4] 2> 1237705 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1237706 T3345 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1237706 T3345 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave
[junit4] 2> 1237707 T3345 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\'
[junit4] 2> 1237729 T3345 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-1374709041813\slave\solr.xml
[junit4] 2> 1237730 T3345 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\solr.xml does not exist, using default configuration
[junit4] 2> 1237753 T3345 oasc.CoreContainer.<init> New CoreContainer 1951797829
[junit4] 2> 1237753 T3345 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\]
[junit4] 2> 1237754 T3345 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1237754 T3345 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1237754 T3345 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1237754 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1237754 T3345 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1237754 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1237755 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1237755 T3345 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1237756 T3345 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1237756 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1237764 T3367 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1
[junit4] 2> 1237764 T3367 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\'
[junit4] 2> 1237782 T3367 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1237786 T3367 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1237787 T3367 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1237789 T3367 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1237798 T3367 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1237799 T3367 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1237799 T3367 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\data\
[junit4] 2> 1237799 T3367 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1237801 T3367 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-1374709041813\slave\collection1\data\
[junit4] 2> 1237801 T3367 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-1374709041813\slave\collection1\data\index/
[junit4] 2> 1237801 T3367 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-1374709041813\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1237801 T3367 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-1374709041813\slave\collection1\data\index
[junit4] 2> 1237801 T3367 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2a9dc9cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d7fd2bf),segFN=segments_1,generation=1}
[junit4] 2> 1237801 T3367 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1237803 T3367 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1237804 T3367 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1237804 T3367 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1237804 T3367 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1237804 T3367 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1237805 T3367 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1237806 T3367 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1237807 T3367 oass.SolrIndexSearcher.<init> Opening Searcher@8486c97 main
[junit4] 2> 1237807 T3367 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1237807 T3367 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1237811 T3367 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 1237811 T3367 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1237811 T3368 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8486c97 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1237812 T3345 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1237812 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1237812 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1237829 T3345 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1951797829
[junit4] 2> 1237830 T3345 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6484988f
[junit4] 2> 1237830 T3345 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> 1237831 T3345 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1237831 T3345 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1237831 T3345 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1237832 T3345 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1237833 T3345 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1237833 T3345 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-1374709041813\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-1374709041813\slave\collection1\data\;done=false>>]
[junit4] 2> 1237834 T3345 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-1374709041813\slave\collection1\data\
[junit4] 2> 1237834 T3345 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-1374709041813\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-1374709041813\slave\collection1\data\index;done=false>>]
[junit4] 2> 1237834 T3345 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-1374709041813\slave\collection1\data\index
[junit4] 2> 1237834 T3345 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1238345 T3345 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1238348 T3345 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61549
[junit4] 2> 1238349 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1238350 T3345 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1238350 T3345 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave
[junit4] 2> 1238350 T3345 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\'
[junit4] 2> 1238365 T3345 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-1374709041813\slave\solr.xml
[junit4] 2> 1238365 T3345 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\solr.xml does not exist, using default configuration
[junit4] 2> 1238370 T3345 oasc.CoreContainer.<init> New CoreContainer 1886563650
[junit4] 2> 1238371 T3345 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\]
[junit4] 2> 1238372 T3345 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1238372 T3345 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1238372 T3345 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1238372 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1238372 T3345 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1238373 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1238373 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1238373 T3345 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1238373 T3345 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1238373 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1238380 T3380 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1
[junit4] 2> 1238380 T3380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\'
[junit4] 2> 1238396 T3380 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1238410 T3380 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1238410 T3380 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1238412 T3380 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1238414 T3380 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1238414 T3380 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 1238414 T3380 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\data\
[junit4] 2> 1238414 T3380 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1238414 T3380 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-1374709041813\slave\collection1\data
[junit4] 2> 1238415 T3380 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-1374709041813\slave\collection1\data\index/
[junit4] 2> 1238416 T3380 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-1374709041813\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1238416 T3380 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-1374709041813\slave\collection1\data\index
[junit4] 2> 1238428 T3380 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-1374709041813\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32d023c0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1238428 T3380 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1238429 T3380 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1238430 T3380 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1238430 T3380 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1238430 T3380 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1238430 T3380 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1238430 T3380 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1238438 T3380 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1238441 T3380 oass.SolrIndexSearcher.<init> Opening Searcher@4d64eb15 main
[junit4] 2> 1238442 T3380 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1238442 T3380 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1238442 T3380 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1238443 T3380 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1238443 T3381 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d64eb15 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1238443 T3345 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1238443 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1238444 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1238464 T3345 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1944577124
[junit4] 2> 1238465 T3345 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1be72d43
[junit4] 2> 1238465 T3345 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> 1238465 T3345 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1238466 T3345 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1238466 T3345 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1238467 T3345 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1238467 T3345 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1238467 T3345 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-1374709041704\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-1374709041704\master\collection1\data\;done=false>>]
[junit4] 2> 1238468 T3345 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-1374709041704\master\collection1\data\
[junit4] 2> 1238468 T3345 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-1374709041704\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-1374709041704\master\collection1\data\index;done=false>>]
[junit4] 2> 1238468 T3345 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-1374709041704\master\collection1\data\index
[junit4] 2> 1238469 T3345 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1238521 T3345 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1238523 T3345 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61552
[junit4] 2> 1238524 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1238524 T3345 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1238524 T3345 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master
[junit4] 2> 1238526 T3345 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\'
[junit4] 2> 1238554 T3345 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-1374709041704\master\solr.xml
[junit4] 2> 1238555 T3345 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\solr.xml does not exist, using default configuration
[junit4] 2> 1238563 T3345 oasc.CoreContainer.<init> New CoreContainer 1508338586
[junit4] 2> 1238563 T3345 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\]
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1238564 T3345 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1238565 T3345 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1238565 T3345 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1238565 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1238570 T3392 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1
[junit4] 2> 1238570 T3392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1\'
[junit4] 2> 1238588 T3392 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1238601 T3392 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1238602 T3392 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1238604 T3392 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1238609 T3392 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1238610 T3392 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 1238610 T3392 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041704\master\collection1\data\
[junit4] 2> 1238610 T3392 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1238612 T3392 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-1374709041704\master\collection1\data
[junit4] 2> 1238613 T3392 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-1374709041704\master\collection1\data\index/
[junit4] 2> 1238615 T3392 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-1374709041704\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1238615 T3392 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-1374709041704\master\collection1\data\index
[junit4] 2> 1238621 T3392 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-1374709041704\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f088fbd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1238622 T3392 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1238622 T3392 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1238623 T3392 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1238623 T3392 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1238623 T3392 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1238623 T3392 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1238623 T3392 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1238628 T3392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1238631 T3392 oass.SolrIndexSearcher.<init> Opening Searcher@2ba5d370 main
[junit4] 2> 1238632 T3392 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1238632 T3392 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1238632 T3392 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 1238633 T3392 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: 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-1374709041704\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f088fbd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1238634 T3392 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1238634 T3392 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1238634 T3392 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1238634 T3393 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ba5d370 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1238635 T3345 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1238635 T3345 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1238635 T3345 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2160 name=collection1 org.apache.solr.core.SolrCore@217f5a74
[junit4] 2> 1238644 T3386 C2160 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1238645 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C2161 name=collection1 org.apache.solr.core.SolrCore@78114c3d
[junit4] 2> 1238648 T3374 C2161 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1238655 T3374 C2161 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: 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-1374709041813\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32d023c0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 1238655 T3374 C2161 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1238655 T3374 C2161 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 7
[junit4] 2> 1238663 T3375 C2161 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1238670 T3375 C2161 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[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-1374709041813\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32d023c0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=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-1374709041813\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32d023c0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 1238670 T3375 C2161 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1238675 T3375 C2161 oass.SolrIndexSearcher.<init> Opening Searcher@24625c7 main
[junit4] 2> 1238675 T3375 C2161 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1238675 T3381 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24625c7 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 1238676 T3375 C2161 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 2> 1238686 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 3
[junit4] 2> 1238689 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 1238692 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 1238695 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 1238697 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 1238700 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 1238702 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 1238705 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4] 2> 1238708 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4] 2> 1238710 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4] 2> 1238713 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 1238715 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 1238718 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 1
[junit4] 2> 1238720 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 1238722 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 1238725 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4] 2> 1238727 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 1238730 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 1238734 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 1
[junit4] 2> 1238736 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 1238738 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 1238741 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 1
[junit4] 2> 1238744 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 1
[junit4] 2> 1238747 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 1238749 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 1238752 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4] 2> 1238755 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 1
[junit4] 2> 1238758 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 1238760 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 1238762 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 1238765 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 1
[junit4] 2> 1238768 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4] 2> 1238771 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 1
[junit4] 2> 1238773 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 1238776 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 1238779 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 1
[junit4] 2> 1238782 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 1
[junit4] 2> 1238785 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 1
[junit4] 2> 1238787 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 1
[junit4] 2> 1238789 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 1
[junit4] 2> 1238791 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 1238793 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 1238796 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 1238799 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 1
[junit4] 2> 1238802 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4] 2> 1238805 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 1
[junit4] 2> 1238807 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 1238810 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 1
[junit4] 2> 1238812 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 1238815 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4] 2> 1238818 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 1
[junit4] 2> 1238820 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 1238822 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4] 2> 1238825 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 1238828 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 1
[junit4] 2> 1238830 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4] 2> 1238833 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 1
[junit4] 2> 1238836 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4] 2> 1238839 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4] 2> 1238843 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4] 2> 1238846 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 1
[junit4] 2> 1238849 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4] 2> 1238851 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 1
[junit4] 2> 1238853 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 1
[junit4] 2> 1238856 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 1
[junit4] 2> 1238858 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 0
[junit4] 2> 1238862 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 1
[junit4] 2> 1238865 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 1
[junit4] 2> 1238867 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 1238869 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 1238874 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4] 2> 1238876 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 0
[junit4] 2> 1238879 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 0
[junit4] 2> 1238883 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4] 2> 1238885 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4] 2> 1238889 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 1
[junit4] 2> 1238892 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 1
[junit4] 2> 1238894 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4] 2> 1238897 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4] 2> 1238899 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 0
[junit4] 2> 1238902 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4] 2> 1238905 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 1
[junit4] 2> 1238907 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4] 2> 1238909 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 0
[junit4] 2> 1238912 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 1238915 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4] 2> 1238918 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 1
[junit4] 2> 1238920 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4] 2> 1238924 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4] 2> 1238927 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4] 2> 1238930 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 1
[junit4] 2> 1238932 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 1
[junit4] 2> 1238934 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 1238937 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 1
[junit4] 2> 1238940 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4] 2> 1238943 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 1
[junit4] 2> 1238945 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 1238948 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4] 2> 1238951 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 1
[junit4] 2> 1238953 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 1
[junit4] 2> 1238956 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4] 2> 1238959 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4] 2> 1238962 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 1
[junit4] 2> 1238965 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 1
[junit4] 2> 1238966 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 1238969 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4] 2> 1238972 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 1
[junit4] 2> 1238974 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4] 2> 1238977 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 1
[junit4] 2> 1238980 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4] 2> 1238983 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 1
[junit4] 2> 1238985 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 1
[junit4] 2> 1238988 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4] 2> 1238992 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 1
[junit4] 2> 1238996 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 1238999 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 1
[junit4] 2> 1239001 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4] 2> 1239003 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4] 2> 1239005 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 0
[junit4] 2> 1239008 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 1239011 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 1
[junit4] 2> 1239013 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4] 2> 1239016 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4] 2> 1239019 T3391 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 1239021 T3386 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 1239024 T3387 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 1
[junit4] 2> 1239027 T3388 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4] 2> 1239030 T3389 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4] 2> 1239032 T3390 C2160 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1239073 T3390 C2160 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[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-1374709041704\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f088fbd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=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-1374709041704\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f088fbd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 1239074 T3390 C2160 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1239079 T3390 C2160 oass.SolrIndexSearcher.<init> Opening Searcher@2192daa4 main
[junit4] 2> 1239080 T3390 C2160 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1239080 T3393 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2192daa4 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C128)}
[junit4] 2> 1239081 T3390 C2160 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 49
[junit4] 2> 1239085 T3391 C2160 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=128 status=0 QTime=2
[junit4] 2> 1239090 T3377 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1239094 T3395 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1239094 T3395 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1239095 T3386 C2160 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1239096 T3395 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 1239096 T3395 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 1239096 T3395 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 1239105 T3387 C2160 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> 1239106 T3395 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 11
[junit4] 2> 1239106 T3395 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-1374709041813\slave\collection1\data\index.20130725073723228
[junit4] 2> 1239107 T3395 oash.SnapPuller.fetchLatestIndex Starting download to 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-1374709041813\slave\collection1\data\index.20130725073723228 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2dcd67a9; maxCacheMB=48.0 maxMergeSizeMB=4.0) fullCopy=true
[junit4] 2> 1239110 T3388 C2160 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> 1239117 T3389 C2160 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_MockFixedIntBlock_0.skp&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1239117 T3395 oash.SnapPuller$DirectoryFileFetcher.fetchPackets WARN No content recieved for file: {name=_0_MockFixedIntBlock_0.skp, size=0}
[junit4] 2> 1239122 T3390 C2160 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_MockFixedIntBlock_0.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C2162 name=collection1 org.apache.solr.core.SolrCore@217f5a74
[junit4] 2> 1239134 T3391 C2162 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_MockFixedIntBlock_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> 1239138 T3386 C2162 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> 1239143 T3387 C2162 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=1
[junit4] 2> 1239146 T3388 C2162 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> 1239150 T3389 C2162 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1239154 T3390 C2162 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_MockFixedIntBlock_0.tib&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1239158 T3391 C2162 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> 1239162 T3386 C2162 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> 1239163 T3395 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 1239165 T3395 oash.SnapPuller.downloadConfFiles Starting download of configuration files from master: [{name=schema.xml, checksum=1505963717, size=1634}]
[junit4] 2> 1239176 T3387 C2162 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&wt=filestream&cf=schema.xml&qt=/replication&generation=2} status=0 QTime=3
[junit4] 2> 1239188 T3395 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130725073723228
[junit4] 2> 1239191 T3395 oash.SnapPuller.fetchLatestIndex removing old index directory 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-1374709041813\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32d023c0; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4] 2> 1239192 T3395 oash.SnapPuller.fetchLatestIndex Configuration files are modified, core will be reloaded
[junit4] 2> 1239197 T3395 oash.SnapPuller.fetchLatestIndex removing old index directory 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-1374709041813\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@32d023c0; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4] 2> 1239197 T3395 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 1239197 T3395 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1239197 T3395 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 1239198 T3397 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1
[junit4] 2> 1239198 T3397 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\'
[junit4] 2> 1239202 T3395 oasc.SolrCore.getNewIndexDir New index directory detected: old=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\data\index/ new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\data\index.20130725073723228
[junit4] 2> 1239208 T3395 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: 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-1374709041813\slave\collection1\data\index.20130725073723228 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2dcd67a9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 1239208 T3395 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1239208 T3395 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1239211 T3395 oass.SolrIndexSearcher.<init> Opening Searcher@7fa5669b main
[junit4] 2> 1239211 T3381 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7fa5669b main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C128)}
[junit4] 2> ASYNC NEW_CORE C2163 name=collection1 org.apache.solr.core.SolrCore@78114c3d
[junit4] 2> 1239212 T3378 C2163 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:61552/solr/replication&command=fetchindex&wait=true} status=0 QTime=119
[junit4] 2> 1239213 T3378 C2163 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-1374709041813\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-1374709041813\slave\collection1\data\index;done=true>>]
[junit4] 2> 1239213 T3378 C2163 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-1374709041813\slave\collection1\data\index
[junit4] 2> 1239213 T3378 C2163 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\data\index
[junit4] 2> 1239220 T3378 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1239238 T3397 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1239250 T3397 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1239251 T3397 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1239255 T3397 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1239259 T3397 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1239261 T3397 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709041813\slave\collection1\data\
[junit4] 2> 1239261 T3397 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1239262 T3397 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-1374709041813\slave\collection1\data\index.20130725073723228
[junit4] 2> 1239262 T3397 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1239263 T3397 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1239263 T3397 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1239263 T3397 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1239263 T3397 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1239264 T3397 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1239270 T3397 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1239272 T3397 oass.SolrIndexSearcher.<init> Opening Searcher@4879065c main
[junit4] 2> 1239273 T3397 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1239273 T3397 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1239273 T3397 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1239273 T3397 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 1239273 T3397 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1239273 T3397 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 1239273 T3398 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4879065c main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C128)}
[junit4] 2> 1239277 T3397 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: 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-1374709041813\slave\collection1\data\index.20130725073723228 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2dcd67a9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 1239277 T3397 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1239278 T3397 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 1239280 T3397 oass.SolrIndexSearcher.<init> Opening Searcher@78db6d91 main
[junit4] 2> 1239280 T3397 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 1239280 T3397 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@78114c3d
[junit4] 2> 1239280 T3397 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> 1239280 T3398 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78db6d91 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C128)}
[junit4] 2> 1239282 T3397 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1239367 T3374 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> ASYNC NEW_CORE C2164 name=collection1 org.apache.solr.core.SolrCore@3c9407ee
[junit4] 2> 1239369 T3376 C2164 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=128 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2165 name=collection1 org.apache.solr.core.SolrCore@217f5a74
[junit4] 2> 1239379 T3388 C2165 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> 1239384 T3390 C2165 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 1239389 T3377 C2164 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> 1239392 T3391 C2165 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 1239394 T3378 C2164 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> 1239401 T3379 C2164 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 1
[junit4] 2> 1239404 T3375 C2164 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 1
[junit4] 2> 1239407 T3374 C2164 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 1
[junit4] 2> 1239408 T3376 C2164 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1239436 T3376 C2164 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[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-1374709041813\slave\collection1\data\index.20130725073723228 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2dcd67a9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[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-1374709041813\slave\collection1\data\index.20130725073723228 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2dcd67a9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3}
[junit4] 2> 1239436 T3376 C2164 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1239442 T3376 C2164 oass.SolrIndexSearcher.<init> Opening Searcher@3981d345 main
[junit4] 2> 1239443 T3376 C2164 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1239443 T3398 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3981d345 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):C128 _1(5.0):C3)}
[junit4] 2> 1239443 T3376 C2164 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 35
[junit4] 2> 1239447 T3386 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 1
[junit4] 2> 1239451 T3387 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 1
[junit4] 2> 1239454 T3389 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 1
[junit4] 2> 1239456 T3388 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4] 2> 1239459 T3390 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 1
[junit4] 2> 1239461 T3391 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 0
[junit4] 2> 1239463 T3386 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4] 2> 1239465 T3387 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 0
[junit4] 2> 1239468 T3389 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 1
[junit4] 2> 1239470 T3388 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 1
[junit4] 2> 1239473 T3390 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 1
[junit4] 2> 1239476 T3391 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 1
[junit4] 2> 1239478 T3386 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4] 2> 1239480 T3387 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 1239483 T3389 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 1
[junit4] 2> 1239485 T3388 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4] 2> 1239488 T3390 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 1
[junit4] 2> 1239491 T3391 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 1
[junit4] 2> 1239494 T3386 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4] 2> 1239497 T3387 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[150]} 0 1
[junit4] 2> 1239500 T3389 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[151]} 0 1
[junit4] 2> 1239502 T3388 C2165 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[152]} 0 0
[...truncated too long message...]
2 T3345 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3823c78a
[junit4] 2> 1324152 T3345 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=496,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1324154 T3345 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1324154 T3345 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1324154 T3345 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1324155 T3345 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1324155 T3345 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1324155 T3345 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-1374709124676\master\collection1\data\index.20130725073847893 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709124676\master\collection1\data\index.20130725073847893;done=false>>]
[junit4] 2> 1324155 T3345 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-1374709124676\master\collection1\data\index.20130725073847893
[junit4] 2> 1324157 T3345 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-1374709124676\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-1374709124676\master\collection1\data\;done=false>>]
[junit4] 2> 1324157 T3345 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-1374709124676\master\collection1\data\
[junit4] 2> 1324158 T3345 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1324225 T3345 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=916861699
[junit4] 2> 1324229 T3345 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3a97904
[junit4] 2> 1324229 T3345 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1324231 T3345 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1324231 T3345 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1324231 T3345 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1324233 T3345 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1324233 T3345 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1324233 T3345 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-1374709124888\slave\collection1\data\index.20130725073847509 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1374709124888\slave\collection1\data\index.20130725073847509;done=false>>]
[junit4] 2> 1324236 T3345 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-1374709124888\slave\collection1\data\index.20130725073847509
[junit4] 2> 1324236 T3345 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-1374709124888\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-1374709124888\slave\collection1\data\;done=false>>]
[junit4] 2> 1324236 T3345 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-1374709124888\slave\collection1\data\
[junit4] 2> 1324237 T3345 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1324333 T3345 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {newname=MockFixedIntBlock(blockSize=318), id=MockFixedIntBlock(blockSize=318), name=MockFixedIntBlock(blockSize=318)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=zh, timezone=Asia/Hong_Kong
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=52876856,total=171249664
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrDeletionPolicy2, HdfsChaosMonkeySafeLeaderTest, FileBasedSpellCheckerTest, QueryElevationComponentTest, DistanceFunctionTest, SystemInfoHandlerTest, DocValuesTest, TestSolrJ, SliceStateTest, TestDistributedGrouping, TestCloudManagedSchema, AlternateDirectoryTest, CoreAdminCreateDiscoverTest, SolrPluginUtilsTest, AliasIntegrationTest, RequiredFieldsTest, SolrRequestParserTest, TestIBSimilarityFactory, TestDynamicFieldCollectionResource, BasicZkTest, DocumentBuilderTest, CollectionsAPIDistributedZkTest, TestLMJelinekMercerSimilarityFactory, CoreAdminHandlerTest, BasicDistributedZkTest, ReturnFieldsTest, JSONWriterTest, HdfsCollectionsAPIDistributedZkTest, CSVRequestHandlerTest, TestSolrQueryParser, SolrIndexSplitterTest, UniqFieldsUpdateProcessorFactoryTest, FullSolrCloudDistribCmdsTest, SuggesterFSTTest, TestBinaryField, TestSolrIndexConfig, SortByFunctionTest, RecoveryZkTest, TestFuzzyAnalyzedSuggestions, StatelessScriptUpdateProcessorFactoryTest, TestComponentsName, UUIDFieldTest, FileUtilsTest, ChaosMonkeyNothingIsSafeTest, XsltUpdateRequestHandlerTest, HighlighterTest, OverseerTest, TestRealTimeGet, URLClassifyProcessorTest, TestGroupingSearch, MBeansHandlerTest, SyncSliceTest, TestLRUCache, TestDistributedSearch, PrimUtilsTest, ParsingFieldUpdateProcessorsTest, TestSolr4Spatial, TestCollationField, TestCharFilters, SolrCoreTest, DirectUpdateHandlerOptimizeTest, TestDefaultSimilarityFactory, PingRequestHandlerTest, TestRandomDVFaceting, DistributedTermsComponentTest, DirectSolrConnectionTest, TestQueryTypes, QueryEqualityTest, DirectUpdateHandlerTest, TestDocSet, SpellingQueryConverterTest, AssignTest, TestLFUCache, IndexSchemaRuntimeFieldTest, DisMaxRequestHandlerTest, SuggesterTSTTest, SolrTestCaseJ4Test, WordBreakSolrSpellCheckerTest, TestMultiCoreConfBootstrap, ShowFileRequestHandlerTest, TestCodecSupport, DateMathParserTest, TestFieldTypeResource, TestBadConfig, ConvertedLegacyTest, HdfsRecoveryZkTest, TestConfig, TestMaxScoreQueryParser, SolrCoreCheckLockOnStartupTest, TestStressVersions, TestClassNameShortening, TestFieldResource, TestSolrCoreProperties, PluginInfoTest, TestRangeQuery, TestSchemaResource, TermsComponentTest, CurrencyFieldXmlFileTest, CopyFieldTest, TestSerializedLuceneMatchVersion, TermVectorComponentDistributedTest, TestSchemaVersionResource, TestRecoveryHdfs, TestLMDirichletSimilarityFactory, TestRecovery, TestReplicationHandler]
[junit4] Completed in 86.78s, 14 tests, 1 failure <<< FAILURES!
[...truncated 646 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, 1342 tests, 1 failure, 42 ignored (11 assumptions)
Total time: 60 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure