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 15:36:23 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b99) - Build #
6911 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/6911/
Java: 32bit/jdk1.8.0-ea-b99 -client -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Wed Aug 07 10:21:00 ART 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Wed Aug 07 10:21:00 ART 2013
at __randomizedtesting.SeedInfo.seed([DF1F7316AD6CEC57:4B473D0A84485E4]: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 9553 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 102277 T672 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 102280 T672 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 102282 T672 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:35574
[junit4] 2> 102282 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 102283 T672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 102283 T672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master
[junit4] 2> 102283 T672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/'
[junit4] 2> 102295 T672 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-1375881660168/master/solr.xml
[junit4] 2> 102295 T672 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/solr.xml does not exist, using default configuration
[junit4] 2> 102306 T672 oasc.CoreContainer.<init> New CoreContainer 16588179
[junit4] 2> 102307 T672 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/]
[junit4] 2> 102307 T672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 102308 T672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 102308 T672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 102308 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 102309 T672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 102309 T672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 102310 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 102310 T672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 102310 T672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 102311 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 102315 T682 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1
[junit4] 2> 102315 T682 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1/'
[junit4] 2> 102332 T682 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 102344 T682 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 102344 T682 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 102347 T682 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 102349 T682 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 102350 T682 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 102350 T682 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1/data/
[junit4] 2> 102350 T682 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 102350 T682 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-1375881660168/master/collection1/data
[junit4] 2> 102351 T682 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-1375881660168/master/collection1/data/index/
[junit4] 2> 102351 T682 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-1375881660168/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 102351 T682 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-1375881660168/master/collection1/data/index
[junit4] 2> 102352 T682 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@118a1bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f4e360),segFN=segments_1,generation=1}
[junit4] 2> 102352 T682 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 102353 T682 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 102353 T682 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 102353 T682 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 102353 T682 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 102354 T682 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 102354 T682 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 102355 T682 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 102356 T682 oass.SolrIndexSearcher.<init> Opening Searcher@1129770 main
[junit4] 2> 102357 T682 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 102357 T682 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 102358 T682 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 102358 T682 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 102358 T682 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 102358 T683 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1129770 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 102359 T672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 102359 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 102359 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 102364 T672 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 102366 T672 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47737
[junit4] 2> 102366 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 102367 T672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 102367 T672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave
[junit4] 2> 102367 T672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/'
[junit4] 2> 102378 T672 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-1375881660253/slave/solr.xml
[junit4] 2> 102379 T672 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/solr.xml does not exist, using default configuration
[junit4] 2> 102393 T672 oasc.CoreContainer.<init> New CoreContainer 23678464
[junit4] 2> 102393 T672 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/]
[junit4] 2> 102394 T672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 102394 T672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 102394 T672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 102395 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 102395 T672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 102395 T672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 102396 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 102396 T672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 102396 T672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 102397 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 102401 T694 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1
[junit4] 2> 102401 T694 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/'
[junit4] 2> 102418 T694 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 102430 T694 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 102431 T694 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 102433 T694 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 102435 T694 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 102436 T694 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 102436 T694 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/
[junit4] 2> 102436 T694 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 102436 T694 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-1375881660253/slave/collection1/data
[junit4] 2> 102437 T694 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-1375881660253/slave/collection1/data/index/
[junit4] 2> 102437 T694 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-1375881660253/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 102437 T694 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-1375881660253/slave/collection1/data/index
[junit4] 2> 102438 T694 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1172033 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1938993),segFN=segments_1,generation=1}
[junit4] 2> 102438 T694 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 102439 T694 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 102439 T694 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 102439 T694 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 102440 T694 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 102440 T694 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 102440 T694 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 102441 T694 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 102442 T694 oass.SolrIndexSearcher.<init> Opening Searcher@187dc06 main
[junit4] 2> 102442 T694 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 102442 T694 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 102444 T694 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 102444 T694 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 102444 T695 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@187dc06 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 102444 T672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 102445 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 102445 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 102470 T672 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23678464
[junit4] 2> 102470 T672 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@539ad9
[junit4] 2> 102471 T672 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> 102471 T672 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 102472 T672 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 102472 T672 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 102472 T672 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 102473 T672 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 102473 T672 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-1375881660253/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-1375881660253/slave/collection1/data;done=false>>]
[junit4] 2> 102473 T672 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-1375881660253/slave/collection1/data
[junit4] 2> 102474 T672 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-1375881660253/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-1375881660253/slave/collection1/data/index;done=false>>]
[junit4] 2> 102474 T672 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-1375881660253/slave/collection1/data/index
[junit4] 2> 102475 T672 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 102527 T672 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 102529 T672 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60215
[junit4] 2> 102529 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 102530 T672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 102530 T672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave
[junit4] 2> 102530 T672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/'
[junit4] 2> 102540 T672 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-1375881660253/slave/solr.xml
[junit4] 2> 102540 T672 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/solr.xml does not exist, using default configuration
[junit4] 2> 102553 T672 oasc.CoreContainer.<init> New CoreContainer 15038694
[junit4] 2> 102554 T672 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/]
[junit4] 2> 102554 T672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 102555 T672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 102555 T672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 102555 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 102556 T672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 102556 T672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 102556 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 102556 T672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 102557 T672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 102557 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 102561 T707 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1
[junit4] 2> 102561 T707 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/'
[junit4] 2> 102576 T707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 102590 T707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 102590 T707 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 102592 T707 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 102594 T707 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 102595 T707 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 102595 T707 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/
[junit4] 2> 102595 T707 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 102596 T707 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-1375881660253/slave/collection1/data
[junit4] 2> 102596 T707 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-1375881660253/slave/collection1/data/index/
[junit4] 2> 102596 T707 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-1375881660253/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 102596 T707 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-1375881660253/slave/collection1/data/index
[junit4] 2> 102597 T707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@101402e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa77ff),segFN=segments_1,generation=1}
[junit4] 2> 102597 T707 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 102598 T707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 102598 T707 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 102598 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 102599 T707 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 102599 T707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 102599 T707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 102600 T707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 102601 T707 oass.SolrIndexSearcher.<init> Opening Searcher@a9ecdb main
[junit4] 2> 102601 T707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 102601 T707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 102602 T707 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 102602 T708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a9ecdb main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 102602 T707 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 102602 T672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 102603 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 102603 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 102628 T672 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16588179
[junit4] 2> 102628 T672 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@92af49
[junit4] 2> 102629 T672 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> 102629 T672 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 102629 T672 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 102630 T672 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 102630 T672 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 102630 T672 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 102631 T672 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-1375881660168/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-1375881660168/master/collection1/data;done=false>>]
[junit4] 2> 102631 T672 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-1375881660168/master/collection1/data
[junit4] 2> 102631 T672 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-1375881660168/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-1375881660168/master/collection1/data/index;done=false>>]
[junit4] 2> 102632 T672 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-1375881660168/master/collection1/data/index
[junit4] 2> 102632 T672 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 102683 T672 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 102685 T672 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60957
[junit4] 2> 102686 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 102686 T672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 102687 T672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master
[junit4] 2> 102687 T672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/'
[junit4] 2> 102696 T672 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-1375881660168/master/solr.xml
[junit4] 2> 102697 T672 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/solr.xml does not exist, using default configuration
[junit4] 2> 102710 T672 oasc.CoreContainer.<init> New CoreContainer 11554728
[junit4] 2> 102711 T672 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/]
[junit4] 2> 102711 T672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 102711 T672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 102712 T672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 102712 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 102712 T672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 102712 T672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 102713 T672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 102713 T672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 102713 T672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 102714 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 102717 T719 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1
[junit4] 2> 102717 T719 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1/'
[junit4] 2> 102736 T719 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 102752 T719 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 102752 T719 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 102754 T719 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 102757 T719 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 102757 T719 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 102757 T719 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660168/master/collection1/data/
[junit4] 2> 102758 T719 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 102758 T719 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-1375881660168/master/collection1/data
[junit4] 2> 102758 T719 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-1375881660168/master/collection1/data/index/
[junit4] 2> 102759 T719 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-1375881660168/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 102759 T719 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-1375881660168/master/collection1/data/index
[junit4] 2> 102760 T719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a86cb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f481ec),segFN=segments_1,generation=1}
[junit4] 2> 102760 T719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 102761 T719 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 102761 T719 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 102761 T719 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 102761 T719 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 102762 T719 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 102762 T719 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 102763 T719 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 102764 T719 oass.SolrIndexSearcher.<init> Opening Searcher@1b58ea4 main
[junit4] 2> 102764 T719 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 102764 T719 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 102764 T719 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 102765 T719 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a86cb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f481ec),segFN=segments_1,generation=1}
[junit4] 2> 102765 T719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 102766 T719 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 102766 T719 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 102766 T720 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b58ea4 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 102766 T672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 102767 T672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 102767 T672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C338 name=collection1 org.apache.solr.core.SolrCore@114a363
[junit4] 2> 102772 T715 C338 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 102773 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C339 name=collection1 org.apache.solr.core.SolrCore@1aff118
[junit4] 2> 102775 T703 C339 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 102776 T703 C339 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@101402e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa77ff),segFN=segments_1,generation=1}
[junit4] 2> 102777 T703 C339 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 102777 T703 C339 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2
[junit4] 2> 102780 T704 C339 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 102780 T704 C339 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@101402e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa77ff),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@101402e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa77ff),segFN=segments_2,generation=2}
[junit4] 2> 102781 T704 C339 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 102781 T704 C339 oass.SolrIndexSearcher.<init> Opening Searcher@1beefc2 main
[junit4] 2> 102782 T704 C339 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 102782 T708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1beefc2 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 102782 T704 C339 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 2
[junit4] 2> 102786 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4] 2> 102789 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 102792 T718 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 102794 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 102797 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4] 2> 102799 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 102802 T718 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 102804 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 102806 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 102809 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4] 2> 102811 T718 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 102813 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 102816 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 102818 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 102821 T718 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 1
[junit4] 2> 102823 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 102825 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 102828 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 102830 T718 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 102833 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 102835 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 102838 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 1
[junit4] 2> 102840 T718 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 102843 T715 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4] 2> 102845 T716 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 102847 T717 C338 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 102850 T717 C338 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a86cb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f481ec),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a86cb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f481ec),segFN=segments_2,generation=2}
[junit4] 2> 102851 T717 C338 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 102852 T717 C338 oass.SolrIndexSearcher.<init> Opening Searcher@37a0cc main
[junit4] 2> 102853 T717 C338 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 102853 T720 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37a0cc main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c25)}
[junit4] 2> 102853 T717 C338 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 2> 102857 T718 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=25 status=0 QTime=1
[junit4] 2> 102861 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 102863 T722 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 102863 T722 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 102864 T716 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 102865 T722 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 102865 T722 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 102865 T722 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 102869 T717 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=2
[junit4] 2> 102870 T722 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 102870 T722 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-1375881660253/slave/collection1/data/index.20130807102100761
[junit4] 2> 102871 T722 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed) fullCopy=true
[junit4] 2> 102876 T718 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> 102881 T715 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 102884 T716 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> 102886 T717 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 102888 T722 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 102888 T722 oash.SnapPuller.downloadConfFiles Starting download of configuration files from master: [{name=schema.xml, checksum=1505963717, size=1634}]
[junit4] 2> 102894 T718 C338 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&wt=filestream&cf=schema.xml&qt=/replication&generation=2} status=0 QTime=2
[junit4] 2> 102908 T722 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130807102100761
[junit4] 2> 102908 T722 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirWrapper(org.apache.lucene.store.RAMDirectory@101402e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa77ff)
[junit4] 2> 102909 T722 oash.SnapPuller.fetchLatestIndex Configuration files are modified, core will be reloaded
[junit4] 2> 102910 T722 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirWrapper(org.apache.lucene.store.RAMDirectory@101402e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fa77ff)
[junit4] 2> 102910 T724 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-1375881660253/slave/collection1
[junit4] 2> 102910 T722 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 102911 T722 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 102911 T722 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 102911 T724 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-1375881660253/slave/collection1/'
[junit4] 2> 102912 T722 oasc.SolrCore.getNewIndexDir New index directory detected: old=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/index/ new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/index.20130807102100761
[junit4] 2> 102913 T722 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed),segFN=segments_2,generation=2}
[junit4] 2> 102913 T722 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 102914 T722 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 102914 T722 oass.SolrIndexSearcher.<init> Opening Searcher@afe12a main
[junit4] 2> 102915 T708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@afe12a main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c25)}
[junit4] 2> ASYNC NEW_CORE C340 name=collection1 org.apache.solr.core.SolrCore@1aff118
[junit4] 2> 102915 T706 C340 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:60957/solr/replication&command=fetchindex&wait=true} status=0 QTime=52
[junit4] 2> 102916 T706 C340 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-1375881660253/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-1375881660253/slave/collection1/data/index;done=true>>]
[junit4] 2> 102916 T706 C340 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-1375881660253/slave/collection1/data/index
[junit4] 2> 102918 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 102929 T724 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 102935 T724 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 102936 T724 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 102944 T724 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 102947 T724 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 102948 T724 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-1375881660253/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/
[junit4] 2> 102948 T724 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 102948 T724 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-1375881660253/slave/collection1/data/index.20130807102100761
[junit4] 2> 102949 T724 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 102949 T724 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 102949 T724 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 102949 T724 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 102950 T724 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 102950 T724 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 102951 T724 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 102953 T724 oass.SolrIndexSearcher.<init> Opening Searcher@67c52c main
[junit4] 2> 102953 T724 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 102953 T724 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 102954 T724 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 102954 T724 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 102954 T725 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67c52c main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c25)}
[junit4] 2> 102954 T724 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 102954 T724 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 102955 T724 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed),segFN=segments_2,generation=2}
[junit4] 2> 102956 T724 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 102956 T724 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 102957 T724 oass.SolrIndexSearcher.<init> Opening Searcher@317747 main
[junit4] 2> 102957 T724 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 102957 T725 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@317747 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c25)}
[junit4] 2> 102957 T724 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1aff118
[junit4] 2> 102957 T724 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> 102958 T724 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 103121 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C341 name=collection1 org.apache.solr.core.SolrCore@171900e
[junit4] 2> 103125 T706 C341 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=25 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C342 name=collection1 org.apache.solr.core.SolrCore@114a363
[junit4] 2> 103131 T715 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 103134 T717 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 103137 T703 C341 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 103140 T718 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 103142 T705 C341 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 103146 T704 C341 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4] 2> 103149 T706 C341 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
[junit4] 2> 103152 T703 C341 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 103154 T705 C341 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 103157 T705 C341 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed),segFN=segments_3,generation=3}
[junit4] 2> 103157 T705 C341 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 103158 T705 C341 oass.SolrIndexSearcher.<init> Opening Searcher@df45e5 main
[junit4] 2> 103158 T705 C341 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 103158 T725 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@df45e5 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):c25 _1(5.0):c3)}
[junit4] 2> 103159 T705 C341 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 2> 103163 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 1
[junit4] 2> 103166 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 1
[junit4] 2> 103168 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 103171 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4] 2> 103173 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 103176 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 103179 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 103182 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 103185 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 103188 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 103191 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 103194 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 103196 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 103199 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 103202 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 1
[junit4] 2> 103204 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 103206 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 0
[junit4] 2> 103209 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 0
[junit4] 2> 103211 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 103214 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 103217 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 103220 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4] 2> 103223 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 1
[junit4] 2> 103225 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 103228 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 1
[junit4] 2> 103230 T715 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 103233 T717 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 0
[junit4] 2> 103236 T718 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 1
[junit4] 2> 103238 T716 C342 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 103242 T716 C342 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a86cb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f481ec),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a86cb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f481ec),segFN=segments_3,generation=3}
[junit4] 2> 103242 T716 C342 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 103243 T716 C342 oass.SolrIndexSearcher.<init> Opening Searcher@196a3b1 main
[junit4] 2> 103244 T716 C342 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 103244 T720 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@196a3b1 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):c25 _1(5.0):c28)}
[junit4] 2> 103245 T716 C342 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 2> 103248 T715 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=53 status=0 QTime=0
[junit4] 2> 103252 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 103254 T726 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 103255 T726 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 103257 T717 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 103258 T726 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 103258 T726 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 103258 T726 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 103261 T716 C342 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> 103262 T726 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 7
[junit4] 2> 103263 T726 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-1375881660253/slave/collection1/data/index.20130807102101154
[junit4] 2> 103264 T726 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b7fb1c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15169b8) fullCopy=true
[junit4] 2> 103266 T715 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 103270 T718 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 103272 T717 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 103275 T716 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 103329 T715 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 103332 T718 C342 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C343 name=collection1 org.apache.solr.core.SolrCore@114a363
[junit4] 2> 103340 T717 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 103341 T726 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 103342 T726 oash.SnapPuller.downloadConfFiles Starting download of configuration files from master: [{name=schema.xml, checksum=265618607, size=1508}]
[junit4] 2> 103344 T716 C343 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filecontent&checksum=true&wt=filestream&cf=schema.xml&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 103364 T726 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130807102101154
[junit4] 2> 103365 T726 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed)
[junit4] 2> 103365 T726 oash.SnapPuller.fetchLatestIndex Configuration files are modified, core will be reloaded
[junit4] 2> 103366 T726 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirWrapper(org.apache.lucene.store.RAMDirectory@1eb4597 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e709ed)
[junit4] 2> 103366 T728 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-1375881660253/slave/collection1
[junit4] 2> 103366 T726 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 103366 T728 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-1375881660253/slave/collection1/'
[junit4] 2> 103366 T726 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 103367 T726 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 103367 T726 oasc.SolrCore.getNewIndexDir New index directory detected: old=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/index.20130807102100761 new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/index.20130807102101154
[junit4] 2> 103368 T726 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b7fb1c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15169b8),segFN=segments_3,generation=3}
[junit4] 2> 103368 T726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 103369 T726 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 103386 T728 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 103400 T728 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 103400 T728 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 103402 T728 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 103405 T728 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 103405 T728 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-1375881660253/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/
[junit4] 2> 103406 T728 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 103406 T728 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-1375881660253/slave/collection1/data/index.20130807102101154
[junit4] 2> 103406 T728 oasc.SolrCore.initIndex ERROR [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/index.20130807102101154' is locked. Throwing exception
[junit4] 2> 103406 T728 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4ab2d1
[junit4] 2> 103407 T728 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 103407 T728 oasc.CoreContainer.recordAndThrow ERROR Unable to reload core: collection1 org.apache.solr.common.SolrException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:831)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:413)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:675)
[junit4] 2> at org.apache.solr.handler.SnapPuller$2.run(SnapPuller.java:688)
[junit4] 2> Caused by: org.apache.lucene.store.LockObtainFailedException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:490)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:754)
[junit4] 2> ... 3 more
[junit4] 2>
[junit4] 2> 103407 T728 oash.SnapPuller$2.run ERROR Could not reload core org.apache.solr.common.SolrException: Unable to reload core: collection1
[junit4] 2> at org.apache.solr.core.CoreContainer.recordAndThrow(CoreContainer.java:930)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:685)
[junit4] 2> at org.apache.solr.handler.SnapPuller$2.run(SnapPuller.java:688)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:831)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:413)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:675)
[junit4] 2> ... 1 more
[junit4] 2> Caused by: org.apache.lucene.store.LockObtainFailedException: Index locked for write for core collection1
[junit4] 2> at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:490)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:754)
[junit4] 2> ... 3 more
[junit4] 2>
[junit4] 2> 103420 T726 oass.SolrIndexSearcher.<init> Opening Searcher@1ad9f55 main
[junit4] 2> 103420 T725 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ad9f55 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):c25 _1(5.0):c28)}
[junit4] 2> ASYNC NEW_CORE C344 name=collection1 org.apache.solr.core.SolrCore@171900e
[junit4] 2> 103421 T706 C344 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:60957/solr/replication&command=fetchindex&wait=true} status=0 QTime=167
[junit4] 2> 103422 T706 C344 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-1375881660253/slave/collection1/data/index.20130807102100761 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375881660253/slave/collection1/data/index.20130807102100761;done=true>>]
[junit4] 2> 103422 T706 C344 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-1375881660253/slave/collection1/data/index.20130807102100761
[junit4] 2> 103424 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 103627 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 103830 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 104033 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 104237 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 104440 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 104644 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 104847 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 105050 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 105253 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 105456 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 105660 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 105863 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 106066 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 106269 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 106472 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 106675 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 106878 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 107081 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 107285 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 107489 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 107693 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 107896 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 108099 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 108302 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 108505 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 108709 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 108912 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 109115 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 109318 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 109521 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 109724 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 109927 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 110130 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 110334 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 110538 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 110741 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 110943 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 111146 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 111349 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 111552 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 111755 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 111958 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 112161 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 112364 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 112567 T706 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 112770 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 112973 T704 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 113175 T703 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 113379 T705 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=col
[...truncated too long message...]
re.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2} hits=4 status=0 QTime=1
[junit4] 2> 162478 T1331 C422 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2} hits=4 status=0 QTime=1
[junit4] 2> 162480 T1321 C423 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 162482 T1322 C423 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 162485 T1320 C423 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 162486 T1332 C422 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=2
[junit4] 2> 162488 T1321 C423 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 162489 T1333 C422 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 162490 T672 oas.SolrTestCaseJ4.tearDown ###Ending testEmptyCommits
[junit4] 2> 162511 T672 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=28101334
[junit4] 2> 162511 T672 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1f3861a
[junit4] 2> 162512 T672 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 162512 T672 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 162512 T672 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 162513 T672 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 162513 T672 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 162514 T672 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 162514 T672 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-1375881719957/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-1375881719957/master/collection1/data/index;done=false>>]
[junit4] 2> 162515 T672 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-1375881719957/master/collection1/data/index
[junit4] 2> 162515 T672 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-1375881719957/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-1375881719957/master/collection1/data;done=false>>]
[junit4] 2> 162515 T672 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-1375881719957/master/collection1/data
[junit4] 2> 162516 T672 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 162587 T672 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=22708752
[junit4] 2> 162588 T672 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@b85c6
[junit4] 2> 162588 T672 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> 162589 T672 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 162589 T672 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 162590 T672 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 162590 T672 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 162590 T672 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 162591 T672 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-1375881720047/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-1375881720047/slave/collection1/data;done=false>>]
[junit4] 2> 162591 T672 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-1375881720047/slave/collection1/data
[junit4] 2> 162591 T672 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-1375881720047/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-1375881720047/slave/collection1/data/index;done=false>>]
[junit4] 2> 162592 T672 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-1375881720047/slave/collection1/data/index
[junit4] 2> 162592 T672 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 162648 T672 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=397), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=397)), sim=DefaultSimilarity, locale=be, timezone=America/Argentina/La_Rioja
[junit4] 2> NOTE: Linux 3.8.0-27-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=32804360,total=107823104
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterFSTTest, DOMUtilTest, TestPostingsSolrHighlighter, TestMaxScoreQueryParser, CoreMergeIndexesAdminHandlerTest, TestLMJelinekMercerSimilarityFactory, MoreLikeThisHandlerTest, SolrCoreTest, SOLR749Test, AutoCommitTest, OverseerCollectionProcessorTest, BasicFunctionalityTest, TestFastLRUCache, TestPerFieldSimilarity, CacheHeaderTest, FieldMutatingUpdateProcessorTest, LeaderElectionTest, StressHdfsTest, UpdateRequestProcessorFactoryTest, AssignTest, ZkControllerTest, TestLuceneMatchVersion, AddSchemaFieldsUpdateProcessorFactoryTest, FileBasedSpellCheckerTest, CSVRequestHandlerTest, TestRTGBase, TestElisionMultitermQuery, RegexBoostProcessorTest, PolyFieldTest, TestLRUCache, SearchHandlerTest, SpellPossibilityIteratorTest, TestFieldTypeResource, ZkNodePropsTest, TestDynamicFieldResource, TestCloudManagedSchemaAddField, UpdateParamsTest, TestSearchPerf, SimpleFacetsTest, TestReplicationHandler]
[junit4] Completed on J0 in 60.40s, 14 tests, 1 failure <<< FAILURES!
[...truncated 769 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: 35 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b99 -client -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b99) - Build #
6912 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
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