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/10/24 03:02:27 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b109) - Build #
7919 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7919/
Java: 64bit/jdk1.8.0-ea-b109 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.ShardSplitTest.testDistribSearch
Error Message:
Wrong doc count on shard1_1 expected:<134> but was:<124>
Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_1 expected:<134> but was:<124>
at __randomizedtesting.SeedInfo.seed([BF9837B934025FD3:3E7EB9A1435D3FEF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:478)
at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
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)
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Thu Oct 24 02:48:31 CEST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Thu Oct 24 02:48:31 CEST 2013
at __randomizedtesting.SeedInfo.seed([BF9837B934025FD3:48EBD9E1F2EAF035]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1515)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1264)
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 10056 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 141500 T289 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 141503 T289 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 141507 T289 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45153
[junit4] 2> 141507 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 141507 T289 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 141508 T289 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master
[junit4] 2> 141508 T289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/'
[junit4] 2> 141516 T289 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/solr.xml
[junit4] 2> 141529 T289 oasc.CoreContainer.<init> New CoreContainer 390653583
[junit4] 2> 141530 T289 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/]
[junit4] 2> 141530 T289 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 141530 T289 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 141530 T289 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 141531 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 141531 T289 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 141531 T289 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 141531 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 141531 T289 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 141532 T289 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 141533 T289 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 141533 T289 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 141534 T299 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1
[junit4] 2> 141534 T299 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/'
[junit4] 2> 141547 T299 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 141559 T299 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 141560 T299 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 141562 T299 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 141564 T299 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 141564 T299 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 141565 T299 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/
[junit4] 2> 141565 T299 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 141565 T299 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data
[junit4] 2> 141565 T299 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index/
[junit4] 2> 141565 T299 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 141566 T299 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index
[junit4] 2> 141566 T299 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5791015625, noCFSRatio=0.5075292401624886]
[junit4] 2> 141566 T299 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@77db5711 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6602937a),segFN=segments_1,generation=1}
[junit4] 2> 141566 T299 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141567 T299 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 141567 T299 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 141567 T299 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 141567 T299 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 141568 T299 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 141568 T299 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 141568 T299 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 141569 T299 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 141570 T299 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 141570 T299 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 141570 T299 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 141571 T299 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@77db5711 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6602937a),segFN=segments_1,generation=1}
[junit4] 2> 141571 T299 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141571 T299 oass.SolrIndexSearcher.<init> Opening Searcher@792258ce main
[junit4] 2> 141571 T299 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 141571 T299 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 141572 T300 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@792258ce main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 141572 T299 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 141572 T289 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 141573 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 141576 T289 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 141577 T289 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53601
[junit4] 2> 141578 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 141578 T289 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 141578 T289 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave
[junit4] 2> 141578 T289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/'
[junit4] 2> 141585 T289 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/solr.xml
[junit4] 2> 141598 T289 oasc.CoreContainer.<init> New CoreContainer 1416973465
[junit4] 2> 141598 T289 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/]
[junit4] 2> 141598 T289 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 141599 T289 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 141599 T289 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 141599 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 141599 T289 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 141599 T289 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 141600 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 141600 T289 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 141600 T289 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 141601 T289 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 141601 T289 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 141602 T311 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1
[junit4] 2> 141602 T311 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/'
[junit4] 2> 141615 T311 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 141627 T311 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 141628 T311 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 141630 T311 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 141632 T311 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 141632 T311 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 141632 T311 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/
[junit4] 2> 141632 T311 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 141633 T311 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data
[junit4] 2> 141633 T311 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index/
[junit4] 2> 141633 T311 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 141633 T311 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index
[junit4] 2> 141634 T311 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5791015625, noCFSRatio=0.5075292401624886]
[junit4] 2> 141634 T311 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4261ba90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@484554cc),segFN=segments_1,generation=1}
[junit4] 2> 141634 T311 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141635 T311 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 141635 T311 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 141635 T311 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 141635 T311 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 141635 T311 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 141636 T311 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 141636 T311 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 141636 T311 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 141637 T311 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 141637 T311 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 141638 T311 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 141638 T311 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4261ba90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@484554cc),segFN=segments_1,generation=1}
[junit4] 2> 141638 T311 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141638 T311 oass.SolrIndexSearcher.<init> Opening Searcher@3b680bd3 main
[junit4] 2> 141641 T311 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 141641 T312 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b680bd3 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 141641 T311 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 141642 T289 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 141642 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 141664 T289 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1416973465
[junit4] 2> 141665 T289 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3eb91bb6
[junit4] 2> 141665 T289 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> 141665 T289 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 141666 T289 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 141666 T289 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 141667 T289 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 141667 T289 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 141667 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index;done=false>>]
[junit4] 2> 141667 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index
[junit4] 2> 141667 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data;done=false>>]
[junit4] 2> 141668 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data
[junit4] 2> 141668 T289 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 141741 T289 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=390653583
[junit4] 2> 141742 T289 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@42286090
[junit4] 2> 141742 T289 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> 141742 T289 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 141743 T289 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 141743 T289 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 141743 T289 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 141744 T289 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 141744 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index;done=false>>]
[junit4] 2> 141744 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index
[junit4] 2> 141744 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data;done=false>>]
[junit4] 2> 141744 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data
[junit4] 2> 141745 T289 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 141797 T289 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 141799 T289 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:43664
[junit4] 2> 141799 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 141800 T289 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 141800 T289 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master
[junit4] 2> 141800 T289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/'
[junit4] 2> 141806 T289 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/solr.xml
[junit4] 2> 141813 T289 oasc.CoreContainer.<init> New CoreContainer 738557111
[junit4] 2> 141814 T289 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/]
[junit4] 2> 141821 T289 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 141822 T289 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 141822 T289 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 141822 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 141823 T289 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 141823 T289 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 141823 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 141823 T289 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 141824 T289 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 141824 T289 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 141825 T289 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 141826 T324 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1
[junit4] 2> 141826 T324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/'
[junit4] 2> 141840 T324 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 141846 T324 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 141847 T324 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 141849 T324 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 141858 T324 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 141858 T324 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 141859 T324 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/
[junit4] 2> 141859 T324 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 141859 T324 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data
[junit4] 2> 141859 T324 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index/
[junit4] 2> 141860 T324 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 141860 T324 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index
[junit4] 2> 141860 T324 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5791015625, noCFSRatio=0.5075292401624886]
[junit4] 2> 141861 T324 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_1,generation=1}
[junit4] 2> 141861 T324 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141862 T324 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 141862 T324 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 141862 T324 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 141862 T324 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 141863 T324 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 141863 T324 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 141863 T324 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 141864 T324 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 141864 T324 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 141864 T324 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 141865 T324 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 141865 T324 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_1,generation=1}
[junit4] 2> 141865 T324 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141865 T324 oass.SolrIndexSearcher.<init> Opening Searcher@6a0caf46 main
[junit4] 2> 141866 T324 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 141866 T324 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 141866 T325 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6a0caf46 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 141866 T324 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 141867 T289 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 141867 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1109 name=collection1 org.apache.solr.core.SolrCore@39dd72fd
[junit4] 2> 141882 T321 C1109 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 141883 T321 C1109 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 8
[junit4] 2> 141887 T322 C1109 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 141888 T322 C1109 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_2,generation=2}
[junit4] 2> 141889 T322 C1109 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 141890 T322 C1109 oass.SolrIndexSearcher.<init> Opening Searcher@224686eb main
[junit4] 2> 141891 T322 C1109 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 141891 T325 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@224686eb main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 141891 T322 C1109 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 5
[junit4] 2> 141912 T323 C1109 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=16
[junit4] 2> 141914 T289 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 141916 T289 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46893
[junit4] 2> 141917 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 141917 T289 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 141918 T289 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave
[junit4] 2> 141918 T289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/'
[junit4] 2> 141928 T289 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/solr.xml
[junit4] 2> 141935 T289 oasc.CoreContainer.<init> New CoreContainer 1948655552
[junit4] 2> 141935 T289 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/]
[junit4] 2> 141936 T289 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 141936 T289 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 141936 T289 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 141936 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 141937 T289 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 141937 T289 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 141937 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 141937 T289 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 141937 T289 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 141938 T289 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 141938 T289 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 141940 T336 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1
[junit4] 2> 141940 T336 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/'
[junit4] 2> 141959 T336 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 141966 T336 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 141967 T336 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 141969 T336 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 141977 T336 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 141978 T336 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 141978 T336 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/
[junit4] 2> 141978 T336 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 141979 T336 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data
[junit4] 2> 141979 T336 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index/
[junit4] 2> 141979 T336 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 141980 T336 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index
[junit4] 2> 141980 T336 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5791015625, noCFSRatio=0.5075292401624886]
[junit4] 2> 141980 T336 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@77637427 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4372817a),segFN=segments_1,generation=1}
[junit4] 2> 141981 T336 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141981 T336 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 141982 T336 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 141982 T336 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 141982 T336 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 141982 T336 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 141982 T336 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 141983 T336 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 141984 T336 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 141985 T336 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 141985 T336 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 141985 T336 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 141986 T336 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@77637427 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4372817a),segFN=segments_1,generation=1}
[junit4] 2> 141986 T336 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141986 T336 oass.SolrIndexSearcher.<init> Opening Searcher@1115e71b main
[junit4] 2> 141987 T336 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 141987 T336 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 141987 T337 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1115e71b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 141988 T289 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 141988 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1110 name=collection1 org.apache.solr.core.SolrCore@24d8edba
[junit4] 2> 141993 T332 C1110 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C1111 name=collection1 org.apache.solr.core.SolrCore@39dd72fd
[junit4] 2> 142003 T320 C1111 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 142007 T321 C1111 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=1
[junit4] 2> 142010 T322 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1
[junit4] 2> 142011 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/'
[junit4] 2> 142030 T322 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 142045 T322 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 142046 T322 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 142048 T322 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 142050 T322 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 142051 T322 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/
[junit4] 2> 142051 T322 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 142051 T322 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index/
[junit4] 2> 142052 T322 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 142052 T322 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 142052 T322 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 142052 T322 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 142052 T322 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 142052 T322 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 142053 T322 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 142053 T322 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 142054 T322 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 142054 T322 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 142055 T322 oass.SolrIndexSearcher.<init> Opening Searcher@6cf8753 main
[junit4] 2> 142055 T322 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 142055 T322 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 142055 T322 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 142055 T340 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6cf8753 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 142055 T322 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 142056 T322 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 142059 T322 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=839013061, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 142060 T322 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_2,generation=2}
[junit4] 2> 142060 T322 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 142060 T322 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 142060 T322 oass.SolrIndexSearcher.<init> Opening Searcher@30776e2e main
[junit4] 2> 142060 T340 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30776e2e main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 142061 T322 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 142061 T322 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@39dd72fd
[junit4] 2> 142061 T322 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> 142061 T322 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 142062 T322 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={core=collection1&qt=/admin/cores&action=reload&wt=javabin&version=2} status=0 QTime=52
[junit4] 2> ASYNC NEW_CORE C1112 name=collection1 org.apache.solr.core.SolrCore@43ec89c6
[junit4] 2> 142066 T323 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=1
[junit4] 2> 142069 T320 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 142075 T321 C1112 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 3
[junit4] 2> 142078 T322 C1112 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 142082 T323 C1112 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 142089 T323 C1112 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@33a10b51 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e497945),segFN=segments_3,generation=3}
[junit4] 2> 142090 T323 C1112 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 142093 T323 C1112 oass.SolrIndexSearcher.<init> Opening Searcher@61e9337a main
[junit4] 2> 142093 T323 C1112 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 142093 T340 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@61e9337a main{StandardDirectoryReader(segments_3:4:nrt _0(4.6):c2)}
[junit4] 2> 142094 T323 C1112 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 13
[junit4] 2> 142098 T320 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=2
[junit4] 2> ASYNC NEW_CORE C1113 name=collection1 org.apache.solr.core.SolrCore@24d8edba
[junit4] 2> 142104 T333 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142105 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142208 T334 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 142208 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142311 T335 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142312 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142414 T333 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142415 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142518 T335 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142519 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142621 T332 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142622 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142725 T334 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142726 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142829 T333 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142830 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142932 T332 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 142933 T289 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 142951 T323 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 142952 T338 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 142952 T338 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 142952 T338 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 142957 T320 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=3
[junit4] 2> 142957 T338 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 142958 T338 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index.20131024024824009
[junit4] 2> 142958 T338 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1587c4b3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@611f6aa1) fullCopy=false
[junit4] 2> 142965 T321 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.cfe&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=2
[junit4] 2> 142970 T322 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.si&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 142973 T323 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.cfs&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 142977 T320 C1112 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 142978 T338 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 142978 T338 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 142979 T338 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 142979 T338 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 142980 T338 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 142980 T338 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 142984 T338 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@77637427 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4372817a),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@77637427 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4372817a),segFN=segments_3,generation=3}
[junit4] 2> 142984 T338 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 142985 T338 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 142986 T338 oass.SolrIndexSearcher.<init> Opening Searcher@47c1aa2b main
[junit4] 2> 142987 T337 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47c1aa2b main{StandardDirectoryReader(segments_3:4:nrt _0(4.6):c2)}
[junit4] 2> 142989 T338 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index.20131024024824009 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index.20131024024824009;done=true>>]
[junit4] 2> 142989 T338 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index.20131024024824009
[junit4] 2> 143036 T334 C1113 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> 143037 T289 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 143059 T289 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=738557111
[junit4] 2> 143059 T289 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@43ec89c6
[junit4] 2> 143060 T289 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> 143060 T289 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 143060 T289 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 143061 T289 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 143061 T289 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 143061 T289 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 143062 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data;done=false>>]
[junit4] 2> 143062 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data
[junit4] 2> 143062 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index;done=false>>]
[junit4] 2> 143062 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702551/master/collection1/data/index
[junit4] 2> 143063 T289 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 143125 T289 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1948655552
[junit4] 2> 143126 T289 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@24d8edba
[junit4] 2> 143126 T289 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> 143127 T289 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 143127 T289 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 143127 T289 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 143128 T289 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 143128 T289 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 143128 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data;done=false>>]
[junit4] 2> 143128 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data
[junit4] 2> 143129 T289 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index;done=false>>]
[junit4] 2> 143129 T289 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575702624/slave/collection1/data/index
[junit4] 2> 143129 T289 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 143187 T289 oas.SolrTestCaseJ4.setUp ###Starting doTestIndexAndConfigReplication
[junit4] 2> 143191 T289 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 143192 T289 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34963
[junit4] 2> 143193 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 143193 T289 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 143194 T289 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master
[junit4] 2> 143194 T289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/'
[junit4] 2> 143211 T289 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/solr.xml
[junit4] 2> 143229 T289 oasc.CoreContainer.<init> New CoreContainer 200483259
[junit4] 2> 143229 T289 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/]
[junit4] 2> 143230 T289 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 143230 T289 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 143230 T289 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 143230 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 143231 T289 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 143231 T289 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 143231 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 143231 T289 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 143231 T289 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 143232 T289 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 143233 T289 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 143234 T351 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1
[junit4] 2> 143234 T351 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/'
[junit4] 2> 143250 T351 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 143265 T351 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 143266 T351 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 143268 T351 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 143271 T351 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 143272 T351 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 143272 T351 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/data/
[junit4] 2> 143272 T351 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 143273 T351 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/data
[junit4] 2> 143273 T351 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/data/index/
[junit4] 2> 143273 T351 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 143274 T351 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704239/master/collection1/data/index
[junit4] 2> 143274 T351 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5791015625, noCFSRatio=0.5075292401624886]
[junit4] 2> 143274 T351 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@36838841 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e0181a7),segFN=segments_1,generation=1}
[junit4] 2> 143275 T351 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 143275 T351 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 143275 T351 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 143275 T351 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 143276 T351 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 143276 T351 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 143276 T351 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 143276 T351 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 143277 T351 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 143278 T351 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 143278 T351 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 143278 T351 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 143279 T351 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@36838841 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e0181a7),segFN=segments_1,generation=1}
[junit4] 2> 143279 T351 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 143279 T351 oass.SolrIndexSearcher.<init> Opening Searcher@4887bdd9 main
[junit4] 2> 143279 T351 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 143279 T351 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 143280 T352 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4887bdd9 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 143280 T351 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 143280 T289 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 143281 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 143284 T289 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 143286 T289 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55051
[junit4] 2> 143287 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 143287 T289 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 143288 T289 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave
[junit4] 2> 143288 T289 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/'
[junit4] 2> 143296 T289 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/solr.xml
[junit4] 2> 143312 T289 oasc.CoreContainer.<init> New CoreContainer 302496405
[junit4] 2> 143312 T289 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/]
[junit4] 2> 143313 T289 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 143313 T289 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 143314 T289 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 143314 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 143314 T289 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 143314 T289 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 143315 T289 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 143315 T289 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 143315 T289 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 143320 T289 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 143320 T289 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 143326 T363 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1
[junit4] 2> 143326 T363 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/'
[junit4] 2> 143356 T363 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 143372 T363 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 143372 T363 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 143375 T363 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 143378 T363 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 143378 T363 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 143379 T363 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/data/
[junit4] 2> 143379 T363 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 143379 T363 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/data
[junit4] 2> 143379 T363 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/data/index/
[junit4] 2> 143380 T363 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 143380 T363 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1382575704332/slave/collection1/data/index
[junit4] 2> 143380 T363 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5791015625, noCFSRatio=0.5075292401624886]
[junit4] 2> 143381 T363 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3068fc84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f94890c),segFN=segments_1,generation=1}
[junit4] 2> 143381 T363 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 143382 T363 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 143382 T363 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 143382 T363 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 143382 T363 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 143382 T363 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 143382 T363 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 143383 T363 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 143383 T363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 143384 T363 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 143384 T363 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 143385 T363 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 143385 T363 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3068fc84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f94890c),segFN=segments_1,generation=1}
[junit4] 2> 143385 T363 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 143385 T363 oass.SolrIndexSearcher.<init> Opening Searcher@759bad58 main
[junit4] 2> 143386 T363 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 143386 T364 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@759bad58 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 143386 T363 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 143391 T289 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 143391 T289 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1114 name=collection1 org.apache.solr.core.SolrCore@4a62fea0
[junit4] 2> 143395 T347 C1114 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[...truncated too long message...]
:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 537029 T842 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 537038 T751 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 537089 T751 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 39022
[junit4] 2> 537090 T751 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1237848690
[junit4] 2> 538091 T751 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 538092 T751 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 538093 T751 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5a3fdf31
[junit4] 2> 538097 T751 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=99,cumulative_deletesById=22,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 538097 T751 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 538097 T751 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 538097 T751 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 538098 T751 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 538098 T751 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 538098 T751 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.cloud.ShardSplitTest-1382575858817/jetty4 [CachedDir<<refCount=0;path=./org.apache.solr.cloud.ShardSplitTest-1382575858817/jetty4;done=false>>]
[junit4] 2> 538099 T751 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.cloud.ShardSplitTest-1382575858817/jetty4
[junit4] 2> 538099 T751 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.cloud.ShardSplitTest-1382575858817/jetty4/index [CachedDir<<refCount=0;path=./org.apache.solr.cloud.ShardSplitTest-1382575858817/jetty4/index;done=false>>]
[junit4] 2> 538099 T751 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.cloud.ShardSplitTest-1382575858817/jetty4/index
[junit4] 2> 538100 T892 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90608491483561996-127.0.0.1:39022_-n_0000000004) am no longer a leader.
[junit4] 2> 538101 T881 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 538112 T751 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 538170 T751 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 538173 T751 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50604 50604
[junit4] 2> 538517 T825 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 538583 T881 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@79132caf name:ZooKeeperConnection Watcher:127.0.0.1:50604/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4] 2> 538583 T881 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4] 2> 538584 T751 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 46473
[junit4] 2> !!!! WARNING: best effort to remove /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ShardSplitTest-1382575858817 FAILED !!!!!
[junit4] 2> 538585 T751 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 538585 T751 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50604 50604
[junit4] 1> "core_node7":{
[junit4] 1> "state":"recovering",
[junit4] 1> "base_url":"http://127.0.0.1:51727",
[junit4] 1> "core":"collection1_shard1_0_replica2",
[junit4] 1> "node_name":"127.0.0.1:51727_"}}},
[junit4] 1> "shard1_1":{
[junit4] 1> "parent":"shard1",
[junit4] 1> "range":"e0000000-ffffffff",
[junit4] 1> "state":"construction",
[junit4] 1> "replicas":{
[junit4] 1> "core_node6":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:36282",
[junit4] 1> "core":"collection1_shard1_1_replica1",
[junit4] 1> "node_name":"127.0.0.1:36282_",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node8":{
[junit4] 1> "state":"recovering",
[junit4] 1> "base_url":"http://127.0.0.1:46473",
[junit4] 1> "core":"collection1_shard1_1_replica2",
[junit4] 1> "node_name":"127.0.0.1:46473_"}}}},
[junit4] 1> "router":{"name":"compositeId"},
[junit4] 1> "maxShardsPerNode":"1"}}
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ShardSplitTest -Dtests.method=testDistribSearch -Dtests.seed=BF9837B934025FD3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_AE -Dtests.timezone=Africa/Tunis -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 241s J1 | ShardSplitTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Wrong doc count on shard1_1 expected:<134> but was:<124>
[junit4] > at __randomizedtesting.SeedInfo.seed([BF9837B934025FD3:3E7EB9A1435D3FEF]:0)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:478)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 538614 T751 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 241124 T750 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> 539601 T842 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ar_AE, timezone=Africa/Tunis
[junit4] 2> NOTE: Linux 3.8.0-31-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=77710448,total=219762688
[junit4] 2> NOTE: All tests run in this JVM: [BlockDirectoryTest, DocumentAnalysisRequestHandlerTest, TestWordDelimiterFilterFactory, SignatureUpdateProcessorFactoryTest, TestStressLucene, TestInfoStreamLogging, AssignTest, DebugComponentTest, SpellingQueryConverterTest, TestSolrJ, TestBinaryResponseWriter, SpellCheckComponentTest, XsltUpdateRequestHandlerTest, SuggesterTSTTest, ZkSolrClientTest, StatsComponentTest, DistanceFunctionTest, PrimUtilsTest, TestDynamicFieldResource, CoreAdminCreateDiscoverTest, UniqFieldsUpdateProcessorFactoryTest, TestFastWriter, TestCSVLoader, XmlUpdateRequestHandlerTest, AlternateDirectoryTest, TestDistributedSearch, TestSolrXmlPersistor, HdfsChaosMonkeySafeLeaderTest, TestCoreDiscovery, TimeZoneUtilsTest, SolrRequestParserTest, TestSchemaSimilarityResource, HdfsCollectionsAPIDistributedZkTest, IndexBasedSpellCheckerTest, DeleteShardTest, TestXIncludeConfig, NoCacheHeaderTest, TestCodecSupport, TestFiltering, MinimalSchemaTest, TestSweetSpotSimilarityFactory, TestIndexingPerformance, TestMaxScoreQueryParser, CachingDirectoryFactoryTest, RAMDirectoryFactoryTest, TestStressRecovery, BasicZkTest, ShardRoutingCustomTest, RequestHandlersTest, BlockCacheTest, TestIBSimilarityFactory, TestFuzzyAnalyzedSuggestions, PathHierarchyTokenizerFactoryTest, TestNumberUtils, SolrPluginUtilsTest, HdfsRecoveryZkTest, TestHashPartitioner, TestQueryTypes, TestFastLRUCache, HdfsLockFactoryTest, SolrCoreCheckLockOnStartupTest, TestPhraseSuggestions, TestFieldTypeResource, ShardSplitTest]
[junit4] Completed on J1 in 242.15s, 1 test, 1 failure <<< FAILURES!
[...truncated 548 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:408: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:483: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1263: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:906: There were test failures: 327 suites, 1453 tests, 2 failures, 39 ignored (6 assumptions)
Total time: 39 minutes 41 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b109 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure