You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/07 16:55:45 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b99) - Build #
6912 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/6912/
Java: 32bit/jdk1.8.0-ea-b99 -server -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Wed Aug 07 22:49:38 CST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Wed Aug 07 22:49:38 CST 2013
at __randomizedtesting.SeedInfo.seed([28F2A88CA7B44469:F359A84AA29C2DDA]: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 9813 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 651136 T2914 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 651140 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 651146 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53657
[junit4] 2> 651146 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 651147 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 651147 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master
[junit4] 2> 651147 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/'
[junit4] 2> 651156 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/solr.xml
[junit4] 2> 651156 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/solr.xml does not exist, using default configuration
[junit4] 2> 651163 T2914 oasc.CoreContainer.<init> New CoreContainer 9541590
[junit4] 2> 651163 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/]
[junit4] 2> 651163 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 651164 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 651164 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 651164 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 651164 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 651165 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 651165 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 651165 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 651165 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 651166 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 651170 T2924 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1
[junit4] 2> 651171 T2924 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/'
[junit4] 2> 651183 T2924 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 651188 T2924 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 651189 T2924 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 651190 T2924 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 651193 T2924 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 651193 T2924 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 651193 T2924 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/
[junit4] 2> 651193 T2924 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 651194 T2924 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data
[junit4] 2> 651194 T2924 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index/
[junit4] 2> 651194 T2924 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 651194 T2924 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index
[junit4] 2> 651195 T2924 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c01be8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f62799),segFN=segments_1,generation=1}
[junit4] 2> 651195 T2924 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 651196 T2924 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 651196 T2924 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 651196 T2924 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 651197 T2924 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 651197 T2924 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 651197 T2924 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 651198 T2924 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 651199 T2924 oass.SolrIndexSearcher.<init> Opening Searcher@1063a97 main
[junit4] 2> 651199 T2924 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 651200 T2924 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 651200 T2924 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 651200 T2924 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 651200 T2925 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1063a97 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 651200 T2924 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 651201 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 651201 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 651201 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 651206 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 651208 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53584
[junit4] 2> 651208 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 651209 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 651209 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave
[junit4] 2> 651209 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/'
[junit4] 2> 651216 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/solr.xml
[junit4] 2> 651216 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/solr.xml does not exist, using default configuration
[junit4] 2> 651222 T2914 oasc.CoreContainer.<init> New CoreContainer 27165417
[junit4] 2> 651223 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/]
[junit4] 2> 651223 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 651223 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 651224 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 651224 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 651224 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 651224 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 651225 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 651225 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 651225 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 651225 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 651229 T2936 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1
[junit4] 2> 651229 T2936 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/'
[junit4] 2> 651247 T2936 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 651252 T2936 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 651252 T2936 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 651254 T2936 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 651256 T2936 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 651257 T2936 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 651257 T2936 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/
[junit4] 2> 651257 T2936 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 651257 T2936 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data
[junit4] 2> 651258 T2936 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index/
[junit4] 2> 651258 T2936 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 651258 T2936 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index
[junit4] 2> 651259 T2936 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a76c74 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@708d8d),segFN=segments_1,generation=1}
[junit4] 2> 651259 T2936 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 651260 T2936 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 651260 T2936 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 651260 T2936 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 651260 T2936 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 651261 T2936 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 651261 T2936 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 651262 T2936 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 651262 T2936 oass.SolrIndexSearcher.<init> Opening Searcher@8cdec5 main
[junit4] 2> 651263 T2936 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 651263 T2936 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 651263 T2936 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 651264 T2937 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8cdec5 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 651264 T2936 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 651264 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 651265 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 651265 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 651289 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27165417
[junit4] 2> 651290 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6c230b
[junit4] 2> 651290 T2914 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> 651291 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 651291 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 651291 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 651292 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 651292 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 651292 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data;done=false>>]
[junit4] 2> 651293 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data
[junit4] 2> 651293 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index;done=false>>]
[junit4] 2> 651293 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index
[junit4] 2> 651294 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 651367 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=9541590
[junit4] 2> 651368 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@16c173c
[junit4] 2> 651368 T2914 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> 651369 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 651369 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 651369 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 651370 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 651370 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 651370 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index;done=false>>]
[junit4] 2> 651371 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index
[junit4] 2> 651371 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data;done=false>>]
[junit4] 2> 651371 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data
[junit4] 2> 651372 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 651423 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 651425 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33153
[junit4] 2> 651425 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 651426 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 651426 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master
[junit4] 2> 651426 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/'
[junit4] 2> 651433 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/solr.xml
[junit4] 2> 651433 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/solr.xml does not exist, using default configuration
[junit4] 2> 651438 T2914 oasc.CoreContainer.<init> New CoreContainer 3263532
[junit4] 2> 651439 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/]
[junit4] 2> 651439 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 651439 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 651440 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 651440 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 651440 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 651440 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 651441 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 651441 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 651441 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 651441 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 651445 T2949 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1
[junit4] 2> 651445 T2949 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/'
[junit4] 2> 651457 T2949 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 651463 T2949 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 651463 T2949 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 651465 T2949 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 651467 T2949 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 651467 T2949 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 651468 T2949 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/
[junit4] 2> 651468 T2949 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 651468 T2949 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data
[junit4] 2> 651468 T2949 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index/
[junit4] 2> 651468 T2949 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 651469 T2949 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index
[junit4] 2> 651469 T2949 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_1,generation=1}
[junit4] 2> 651470 T2949 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 651470 T2949 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 651470 T2949 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 651471 T2949 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 651471 T2949 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 651471 T2949 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 651471 T2949 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 651472 T2949 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 651473 T2949 oass.SolrIndexSearcher.<init> Opening Searcher@513f12 main
[junit4] 2> 651473 T2949 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 651473 T2949 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 651473 T2949 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 651474 T2949 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_1,generation=1}
[junit4] 2> 651474 T2949 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 651474 T2949 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 651475 T2950 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@513f12 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 651475 T2949 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 651475 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 651475 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 651476 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C1735 name=collection1 org.apache.solr.core.SolrCore@db4e0d
[junit4] 2> 651479 T2945 C1735 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 651480 T2945 C1735 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 651482 T2946 C1735 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 651483 T2946 C1735 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_2,generation=2}
[junit4] 2> 651484 T2946 C1735 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 651485 T2946 C1735 oass.SolrIndexSearcher.<init> Opening Searcher@1668731 main
[junit4] 2> 651485 T2946 C1735 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 651485 T2950 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1668731 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 651485 T2946 C1735 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4] 2> 651489 T2947 C1735 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2
[junit4] 2> 651491 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 651493 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49056
[junit4] 2> 651493 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 651493 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 651493 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave
[junit4] 2> 651494 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/'
[junit4] 2> 651501 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/solr.xml
[junit4] 2> 651501 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/solr.xml does not exist, using default configuration
[junit4] 2> 651506 T2914 oasc.CoreContainer.<init> New CoreContainer 8567556
[junit4] 2> 651506 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/]
[junit4] 2> 651507 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 651507 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 651507 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 651508 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 651508 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 651508 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 651508 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 651508 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 651509 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 651509 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 651513 T2961 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1
[junit4] 2> 651513 T2961 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/'
[junit4] 2> 651525 T2961 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 651530 T2961 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 651531 T2961 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 651532 T2961 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 651534 T2961 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 651535 T2961 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 651535 T2961 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/
[junit4] 2> 651535 T2961 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 651535 T2961 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data
[junit4] 2> 651536 T2961 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index/
[junit4] 2> 651536 T2961 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 651536 T2961 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index
[junit4] 2> 651537 T2961 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1328909 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aeea1e),segFN=segments_1,generation=1}
[junit4] 2> 651537 T2961 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 651538 T2961 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 651538 T2961 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 651538 T2961 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 651538 T2961 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 651538 T2961 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 651538 T2961 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 651539 T2961 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 651540 T2961 oass.SolrIndexSearcher.<init> Opening Searcher@1378698 main
[junit4] 2> 651540 T2961 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 651540 T2961 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 651541 T2961 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 651541 T2961 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 651541 T2962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1378698 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 651541 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 651542 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 651542 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C1736 name=collection1 org.apache.solr.core.SolrCore@ace43c
[junit4] 2> 651546 T2957 C1736 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 651548 T2948 C1735 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 651550 T2945 C1735 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 651552 T2946 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1
[junit4] 2> 651552 T2946 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/'
[junit4] 2> 651569 T2946 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 651581 T2946 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 651581 T2946 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 651583 T2946 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 651585 T2946 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 651586 T2946 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/
[junit4] 2> 651586 T2946 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 651586 T2946 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index/
[junit4] 2> 651587 T2946 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 651587 T2946 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 651587 T2946 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 651587 T2946 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 651588 T2946 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 651588 T2946 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 651589 T2946 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 651590 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@1ce4164 main
[junit4] 2> 651590 T2946 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 651590 T2946 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 651590 T2946 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 651591 T2946 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 651591 T2946 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 651591 T2946 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 651591 T2946 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 651591 T2965 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ce4164 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 651592 T2946 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_2,generation=2}
[junit4] 2> 651593 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 651593 T2946 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 651593 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@1ec15b6 main
[junit4] 2> 651594 T2965 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ec15b6 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 651594 T2946 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 651594 T2946 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@db4e0d
[junit4] 2> 651594 T2946 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> 651595 T2946 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 651595 T2946 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=44
[junit4] 2> ASYNC NEW_CORE C1737 name=collection1 org.apache.solr.core.SolrCore@228de7
[junit4] 2> 651598 T2947 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 651600 T2948 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 651602 T2945 C1737 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 651604 T2946 C1737 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 651605 T2947 C1737 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 651607 T2947 C1737 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@39d99c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@afd81f),segFN=segments_3,generation=3}
[junit4] 2> 651608 T2947 C1737 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 651609 T2947 C1737 oass.SolrIndexSearcher.<init> Opening Searcher@97a55c main
[junit4] 2> 651609 T2947 C1737 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 651609 T2965 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@97a55c main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):c2)}
[junit4] 2> 651609 T2947 C1737 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 651612 T2948 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C1738 name=collection1 org.apache.solr.core.SolrCore@ace43c
[junit4] 2> 651614 T2958 C1738 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 651614 T2914 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 651716 T2959 C1738 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 651717 T2914 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 651819 T2960 C1738 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 651820 T2914 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 651922 T2958 C1738 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 651922 T2914 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 652025 T2960 C1738 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 652025 T2914 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 652085 T2947 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 652086 T2963 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 652086 T2963 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 652086 T2963 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 652088 T2948 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1
[junit4] 2> 652089 T2963 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 652090 T2963 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index.20130807224918005
[junit4] 2> 652090 T2963 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@146a177 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16a6c59) fullCopy=false
[junit4] 2> 652092 T2945 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 652095 T2946 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 652097 T2947 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 652100 T2948 C1737 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 652101 T2963 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 652101 T2963 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 652101 T2963 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 652102 T2963 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 652103 T2963 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1328909 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aeea1e),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1328909 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aeea1e),segFN=segments_3,generation=3}
[junit4] 2> 652103 T2963 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 652104 T2963 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 652104 T2963 oass.SolrIndexSearcher.<init> Opening Searcher@1f7d749 main
[junit4] 2> 652104 T2962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f7d749 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):c2)}
[junit4] 2> 652105 T2963 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index.20130807224918005 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index.20130807224918005;done=true>>]
[junit4] 2> 652105 T2963 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index.20130807224918005
[junit4] 2> 652127 T2957 C1738 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 652128 T2914 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 652150 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3263532
[junit4] 2> 652151 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@228de7
[junit4] 2> 652151 T2914 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 652152 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 652152 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 652152 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 652153 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 652153 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 652154 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index;done=false>>]
[junit4] 2> 652154 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data/index
[junit4] 2> 652154 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data;done=false>>]
[junit4] 2> 652155 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957053/master/collection1/data
[junit4] 2> 652155 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 652229 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8567556
[junit4] 2> 652229 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@ace43c
[junit4] 2> 652230 T2914 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> 652230 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 652230 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 652231 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 652232 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 652232 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 652232 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data;done=false>>]
[junit4] 2> 652233 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data
[junit4] 2> 652233 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index;done=false>>]
[junit4] 2> 652233 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886957119/slave/collection1/data/index
[junit4] 2> 652234 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 652291 T2914 oas.SolrTestCaseJ4.setUp ###Starting doTestSnapPullWithMasterUrl
[junit4] 2> 652294 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 652296 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48147
[junit4] 2> 652296 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 652296 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 652297 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master
[junit4] 2> 652297 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/'
[junit4] 2> 652306 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/solr.xml
[junit4] 2> 652306 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/solr.xml does not exist, using default configuration
[junit4] 2> 652311 T2914 oasc.CoreContainer.<init> New CoreContainer 11380362
[junit4] 2> 652312 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/]
[junit4] 2> 652312 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 652312 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 652313 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 652313 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 652313 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 652313 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 652314 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 652314 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 652314 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 652314 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 652318 T2976 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1
[junit4] 2> 652319 T2976 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/'
[junit4] 2> 652331 T2976 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 652336 T2976 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 652337 T2976 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 652338 T2976 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 652341 T2976 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 652341 T2976 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 652341 T2976 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/data/
[junit4] 2> 652342 T2976 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 652342 T2976 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/data
[junit4] 2> 652342 T2976 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/data/index/
[junit4] 2> 652342 T2976 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 652342 T2976 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958207/master/collection1/data/index
[junit4] 2> 652343 T2976 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12b52e2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@512206),segFN=segments_1,generation=1}
[junit4] 2> 652344 T2976 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 652344 T2976 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 652344 T2976 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 652345 T2976 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 652345 T2976 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 652345 T2976 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 652345 T2976 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 652346 T2976 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 652347 T2976 oass.SolrIndexSearcher.<init> Opening Searcher@1b31f5f main
[junit4] 2> 652347 T2976 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 652348 T2976 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 652348 T2976 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 652348 T2976 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 652348 T2976 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 652348 T2977 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b31f5f main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 652349 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 652349 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 652349 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 652354 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 652355 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53887
[junit4] 2> 652356 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 652356 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 652357 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave
[junit4] 2> 652357 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/'
[junit4] 2> 652364 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/solr.xml
[junit4] 2> 652364 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/solr.xml does not exist, using default configuration
[junit4] 2> 652369 T2914 oasc.CoreContainer.<init> New CoreContainer 20463921
[junit4] 2> 652369 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/]
[junit4] 2> 652370 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 652370 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 652370 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 652370 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 652371 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 652371 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 652371 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 652371 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 652372 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 652372 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 652375 T2988 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1
[junit4] 2> 652376 T2988 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/'
[junit4] 2> 652388 T2988 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 652393 T2988 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 652393 T2988 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 652395 T2988 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 652397 T2988 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 652397 T2988 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 652398 T2988 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/
[junit4] 2> 652398 T2988 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 652398 T2988 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data
[junit4] 2> 652398 T2988 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index/
[junit4] 2> 652398 T2988 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 652399 T2988 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index
[junit4] 2> 652400 T2988 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c47d49 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@811f5),segFN=segments_1,generation=1}
[junit4] 2> 652400 T2988 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 652400 T2988 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 652401 T2988 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 652401 T2988 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 652401 T2988 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 652401 T2988 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 652401 T2988 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 652402 T2988 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 652403 T2988 oass.SolrIndexSearcher.<init> Opening Searcher@2ed384 main
[junit4] 2> 652403 T2988 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 652403 T2988 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 652404 T2988 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 652404 T2988 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 652404 T2989 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ed384 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 652404 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 652405 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 652405 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 652430 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20463921
[junit4] 2> 652431 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12138af
[junit4] 2> 652432 T2914 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> 652432 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 652432 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 652432 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 652433 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 652433 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 652434 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data;done=false>>]
[junit4] 2> 652434 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data
[junit4] 2> 652434 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index;done=false>>]
[junit4] 2> 652434 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index
[junit4] 2> 652435 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 652486 T2914 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 652489 T2914 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56830
[junit4] 2> 652489 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 652489 T2914 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 652490 T2914 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave
[junit4] 2> 652490 T2914 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/'
[junit4] 2> 652497 T2914 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/solr.xml
[junit4] 2> 652497 T2914 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/solr.xml does not exist, using default configuration
[junit4] 2> 652502 T2914 oasc.CoreContainer.<init> New CoreContainer 24551008
[junit4] 2> 652502 T2914 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/]
[junit4] 2> 652502 T2914 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 652503 T2914 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 652503 T2914 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 652503 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 652503 T2914 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 652504 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 652504 T2914 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 652504 T2914 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 652505 T2914 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 652505 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 652508 T3001 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1
[junit4] 2> 652509 T3001 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/'
[junit4] 2> 652527 T3001 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 652533 T3001 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 652533 T3001 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 652535 T3001 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 652537 T3001 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 652538 T3001 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 652538 T3001 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/
[junit4] 2> 652538 T3001 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 652538 T3001 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data
[junit4] 2> 652539 T3001 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index/
[junit4] 2> 652539 T3001 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 652539 T3001 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375886958267/slave/collection1/data/index
[junit4] 2> 652540 T3001 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cdc9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e18bb3),segFN=segments_1,generation=1}
[junit4] 2> 652540 T3001 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 652541 T3001 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 652541 T3001 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 652541 T3001 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 652541 T3001 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 652541 T3001 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 652542 T3001 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 652542 T3001 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 652543 T3001 oass.SolrIndexSearcher.<init> Opening Searcher@155a147 main
[junit4] 2> 652543 T3001 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 652543 T3001 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 652544 T3001 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 652544 T3002 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@155a147 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 652544 T3001 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 652544 T2914 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 652545 T2914 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 652545 T2914 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C1739 name=collection1 org.apache.solr.core.SolrCore@1f81d9e
[junit4] 2> 652549 T2972 C1739 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 652550 T2972 C1739 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12b52e2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@512206),segFN=segments_1,generation=1}
[junit4] 2> 652551 T2972 C1739 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 652551 T2972 C1739 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2
[junit4] 2> ASYNC NEW_CORE C1740 name=collection1 org.apache.solr.core.SolrCore@bedd5a
[junit4] 2> 652553 T2997 C1740 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 652554 T2997 C1740 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cdc9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e18bb3),segFN=segments_1,generation=1}
[junit4] 2> 652555 T2997 C1740 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 652555 T2997 C1740 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2
[junit4] 2> 652557 T2998 C1740 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 652558 T2998 C1740 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cdc9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e1
[...truncated too long message...]
us=0 QTime=1
[junit4] 2> 709830 T3603 oash.SnapShooter.createSnapshot Creating backup snapshot...
[junit4] 2> 709830 T3594 C1785 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={numberToKeep=1&command=backup} status=0 QTime=0
[junit4] 2> 710032 T3593 C1785 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0
[junit4] 2> 710035 T2914 oas.SolrTestCaseJ4.tearDown ###Ending doTestBackup
[junit4] 2> 710056 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6727476
[junit4] 2> 710056 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@28ce2f
[junit4] 2> 710057 T2914 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=492,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 710057 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 710057 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 710058 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 710059 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 710059 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 710060 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013310/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013310/master/collection1/data;done=false>>]
[junit4] 2> 710060 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013310/master/collection1/data
[junit4] 2> 710061 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013310/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013310/master/collection1/data/index;done=false>>]
[junit4] 2> 710061 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013310/master/collection1/data/index
[junit4] 2> 710062 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 710084 T3584 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:36520/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:36520/solr
[junit4] 2> 710134 T2914 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=32622347
[junit4] 2> 710135 T2914 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7c7fbb
[junit4] 2> 710135 T2914 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> 710135 T2914 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 710136 T2914 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 710136 T2914 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 710136 T2914 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 710137 T2914 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 710137 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013399/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013399/slave/collection1/data;done=false>>]
[junit4] 2> 710137 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013399/slave/collection1/data
[junit4] 2> 710137 T2914 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013399/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013399/slave/collection1/data/index;done=false>>]
[junit4] 2> 710138 T2914 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375887013399/slave/collection1/data/index
[junit4] 2> 710138 T2914 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 710194 T2914 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {newname=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting), name=PostingsFormat(name=Asserting)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=sl_SI, timezone=Asia/Harbin
[junit4] 2> NOTE: Linux 3.8.0-27-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=118686360,total=347078656
[junit4] 2> NOTE: All tests run in this JVM: [AnalysisAfterCoreReloadTest, TestSolrQueryParserResource, TestStressVersions, EchoParamsTest, InfoHandlerTest, TestRealTimeGet, LukeRequestHandlerTest, TestJmxMonitoredMap, CopyFieldTest, SpatialFilterTest, SystemInfoHandlerTest, MBeansHandlerTest, SolrCoreTest, LegacyHTMLStripCharFilterTest, ShardRoutingTest, TestQueryUtils, TestSchemaNameResource, OverseerCollectionProcessorTest, TestSystemIdResolver, RegexBoostProcessorTest, PolyFieldTest, CoreMergeIndexesAdminHandlerTest, ZkNodePropsTest, TestRemoteStreaming, TestDocSet, BinaryUpdateRequestHandlerTest, DateFieldTest, TestRTGBase, PeerSyncTest, MoreLikeThisHandlerTest, SOLR749Test, CacheHeaderTest, ClusterStateUpdateTest, HdfsChaosMonkeySafeLeaderTest, TestSearchPerf, AssignTest, TestLRUCache, TestMaxScoreQueryParser, CachingDirectoryFactoryTest, TestJoin, ShowFileRequestHandlerTest, TestSurroundQueryParser, TestLuceneMatchVersion, TestMergePolicyConfig, TestFieldTypeResource, StatelessScriptUpdateProcessorFactoryTest, TestReversedWildcardFilterFactory, SimpleFacetsTest, SuggesterTest, SampleTest, TestLMJelinekMercerSimilarityFactory, SynonymTokenizerTest, UpdateRequestProcessorFactoryTest, TestValueSourceCache, TestSolrDeletionPolicy1, SolrInfoMBeanTest, SearchHandlerTest, TermsComponentTest, LeaderElectionTest, DocumentBuilderTest, CoreAdminHandlerTest, ParsingFieldUpdateProcessorsTest, SyncSliceTest, BasicFunctionalityTest, ScriptEngineTest, TestPHPSerializedResponseWriter, TestCloudManagedSchemaAddField, TestFastLRUCache, SuggesterFSTTest, TestRandomDVFaceting, ReturnFieldsTest, DistributedSpellCheckComponentTest, QueryEqualityTest, LoggingHandlerTest, CSVRequestHandlerTest, BasicDistributedZk2Test, UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed on J0 in 59.08s, 14 tests, 1 failure <<< FAILURES!
[...truncated 461 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:389: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:369: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1230: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:873: There were test failures: 317 suites, 1343 tests, 1 failure, 36 ignored (6 assumptions)
Total time: 36 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b99 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure