You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/07/31 19:17:38 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b99) - Build #
6737 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6737/
Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Wed Jul 31 19:13:04 CEST 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Wed Jul 31 19:13:04 CEST 2013
at __randomizedtesting.SeedInfo.seed([3DC15ADB82CEA19D:CAB2B48344260E7B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1507)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1256)
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 10027 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 724631 T2685 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4] 2> 724634 T2685 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 724662 T2685 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54955
[junit4] 2> 724662 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 724663 T2685 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 724663 T2685 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master
[junit4] 2> 724663 T2685 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/'
[junit4] 2> 724681 T2685 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-1375290756710/master/solr.xml
[junit4] 2> 724682 T2685 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/solr.xml does not exist, using default configuration
[junit4] 2> 724692 T2685 oasc.CoreContainer.<init> New CoreContainer 32237011
[junit4] 2> 724692 T2685 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/]
[junit4] 2> 724693 T2685 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 724694 T2685 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 724694 T2685 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 724694 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 724695 T2685 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 724695 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 724696 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 724696 T2685 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 724696 T2685 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 724697 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 724704 T2695 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/collection1
[junit4] 2> 724705 T2695 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/collection1/'
[junit4] 2> 724716 T2695 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 724722 T2695 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 724722 T2695 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 724724 T2695 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 724727 T2695 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 724727 T2695 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 724727 T2695 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756710/master/collection1/data/
[junit4] 2> 724727 T2695 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 724728 T2695 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-1375290756710/master/collection1/data
[junit4] 2> 724728 T2695 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-1375290756710/master/collection1/data/index/
[junit4] 2> 724728 T2695 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-1375290756710/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 724728 T2695 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-1375290756710/master/collection1/data/index
[junit4] 2> 724729 T2695 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_1,generation=1}
[junit4] 2> 724729 T2695 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 724730 T2695 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 724730 T2695 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 724730 T2695 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 724730 T2695 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 724731 T2695 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 724731 T2695 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 724733 T2695 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 724738 T2695 oass.SolrIndexSearcher.<init> Opening Searcher@6b22ecc0 main
[junit4] 2> 724738 T2695 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 724738 T2695 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 724738 T2695 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 724738 T2695 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 724739 T2696 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b22ecc0 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 724739 T2695 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 724739 T2685 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 724739 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 724740 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 724744 T2685 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 724746 T2685 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60377
[junit4] 2> 724747 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 724748 T2685 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 724748 T2685 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave
[junit4] 2> 724748 T2685 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/'
[junit4] 2> 724760 T2685 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-1375290756821/slave/solr.xml
[junit4] 2> 724761 T2685 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/solr.xml does not exist, using default configuration
[junit4] 2> 724769 T2685 oasc.CoreContainer.<init> New CoreContainer 64384170
[junit4] 2> 724769 T2685 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/]
[junit4] 2> 724769 T2685 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 724770 T2685 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 724770 T2685 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 724770 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 724770 T2685 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 724771 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 724771 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 724771 T2685 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 724772 T2685 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 724772 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 724777 T2707 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/collection1
[junit4] 2> 724777 T2707 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/collection1/'
[junit4] 2> 724790 T2707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 724800 T2707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 724801 T2707 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 724803 T2707 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 724805 T2707 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 724806 T2707 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 724806 T2707 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290756821/slave/collection1/data/
[junit4] 2> 724806 T2707 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 724806 T2707 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-1375290756821/slave/collection1/data
[junit4] 2> 724806 T2707 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-1375290756821/slave/collection1/data/index/
[junit4] 2> 724806 T2707 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-1375290756821/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 724807 T2707 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-1375290756821/slave/collection1/data/index
[junit4] 2> 724808 T2707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37ea7c32 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19614e89),segFN=segments_1,generation=1}
[junit4] 2> 724808 T2707 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 724808 T2707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 724809 T2707 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 724809 T2707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 724809 T2707 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 724809 T2707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 724809 T2707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 724810 T2707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 724811 T2707 oass.SolrIndexSearcher.<init> Opening Searcher@105540de main
[junit4] 2> 724811 T2707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 724811 T2707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 724811 T2707 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 724812 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@105540de main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 724812 T2707 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 724812 T2685 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 724813 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 724813 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2411 name=collection1 org.apache.solr.core.SolrCore@435c451
[junit4] 2> 724824 T2691 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=4
[junit4] 2> 724828 T2692 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 724832 T2693 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2412 name=collection1 org.apache.solr.core.SolrCore@660bdfa6
[junit4] 2> 724833 T2703 C2412 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 724837 T2694 C2411 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_1,generation=1}
[junit4] 2> 724839 T2694 C2411 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 724840 T2694 C2411 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 5
[junit4] 2> 724843 T2691 C2411 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 724846 T2691 C2411 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_2,generation=2}
[junit4] 2> 724847 T2691 C2411 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 724849 T2691 C2411 oass.SolrIndexSearcher.<init> Opening Searcher@50a2923a main
[junit4] 2> 724850 T2691 C2411 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 724850 T2696 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50a2923a main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C1)}
[junit4] 2> 724851 T2691 C2411 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 2> 724855 T2711 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 724855 T2711 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 724858 T2692 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 724859 T2711 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 724859 T2711 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 724859 T2711 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 724862 T2693 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=1
[junit4] 2> 724863 T2711 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 724864 T2711 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-1375290756821/slave/collection1/data/index.20130731191236943
[junit4] 2> 724864 T2711 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@561b3b0b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2937427a) fullCopy=false
[junit4] 2> 724866 T2694 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724869 T2691 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724872 T2692 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724875 T2693 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724878 T2694 C2411 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2413 name=collection1 org.apache.solr.core.SolrCore@435c451
[junit4] 2> 724884 T2691 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1
[junit4] 2> 724887 T2692 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724890 T2693 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724893 T2694 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724896 T2691 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 724897 T2711 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 724898 T2711 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 724898 T2711 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 724899 T2711 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 724900 T2711 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37ea7c32 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19614e89),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37ea7c32 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19614e89),segFN=segments_2,generation=2}
[junit4] 2> 724900 T2711 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 724901 T2711 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 724902 T2711 oass.SolrIndexSearcher.<init> Opening Searcher@65e4f735 main
[junit4] 2> 724902 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@65e4f735 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C1)}
[junit4] 2> 724902 T2711 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-1375290756821/slave/collection1/data/index.20130731191236943 [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-1375290756821/slave/collection1/data/index.20130731191236943;done=true>>]
[junit4] 2> 724902 T2711 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-1375290756821/slave/collection1/data/index.20130731191236943
[junit4] 2> ASYNC NEW_CORE C2414 name=collection1 org.apache.solr.core.SolrCore@660bdfa6
[junit4] 2> 724903 T2704 C2414 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:54955/solr/replication&command=fetchindex&wait=true} status=0 QTime=49
[junit4] 2> 724910 T2692 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 724911 T2705 C2414 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=5
[junit4] 2> 724915 T2693 C2413 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 724918 T2694 C2413 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 724922 T2694 C2413 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_3,generation=3}
[junit4] 2> 724922 T2694 C2413 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 724923 T2694 C2413 oass.SolrIndexSearcher.<init> Opening Searcher@7b9e6b99 main
[junit4] 2> 724924 T2691 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 724924 T2696 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b9e6b99 main{StandardDirectoryReader(segments_3:5:nrt _0(4.5):C1 _1(4.5):C1)}
[junit4] 2> 724924 T2694 C2413 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 724925 T2709 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 724925 T2709 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 724925 T2709 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 724925 T2694 C2413 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 2> 724929 T2692 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=2
[junit4] 2> 724929 T2706 C2414 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:54955/solr/replication&command=fetchindex&wait=true} status=0 QTime=1
[junit4] 2> 724930 T2709 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 19
[junit4] 2> 724931 T2709 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-1375290756821/slave/collection1/data/index.20130731191237009
[junit4] 2> 724931 T2709 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1a33324d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@693d7462) fullCopy=false
[junit4] 2> 724934 T2693 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfs&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724935 T2691 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 724937 T2703 C2414 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 724937 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fnm because it already exists
[junit4] 2> 724940 T2694 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.frq&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724941 T2692 C2413 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 724942 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfs because it already exists
[junit4] 2> 724943 T2691 C2413 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 724944 T2693 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724946 T2691 C2413 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9634c96 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2517f052),segFN=segments_4,generation=4}
[junit4] 2> 724947 T2691 C2413 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 724947 T2692 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfe&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724948 T2691 C2413 oass.SolrIndexSearcher.<init> Opening Searcher@54dfe503 main
[junit4] 2> 724948 T2691 C2413 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 724948 T2696 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@54dfe503 main{StandardDirectoryReader(segments_4:7:nrt _0(4.5):C1 _1(4.5):C1 _2(4.5):C1)}
[junit4] 2> 724949 T2691 C2413 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 2> 724951 T2694 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724952 T2704 C2414 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:54955/solr/replication&command=fetchindex&wait=true} status=0 QTime=1
[junit4] 2> 724955 T2693 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 724957 T2685 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 724958 T2692 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724960 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.frq because it already exists
[junit4] 2> 724960 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.tim because it already exists
[junit4] 2> 724960 T2685 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51933
[junit4] 2> 724961 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0_nrm.cfe because it already exists
[junit4] 2> 724961 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdx because it already exists
[junit4] 2> 724962 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 724961 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 724962 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.tip because it already exists
[junit4] 2> 724962 T2685 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 724963 T2685 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater
[junit4] 2> 724963 T2685 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/'
[junit4] 2> 724965 T2691 C2413 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.tip&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724966 T2709 oash.SnapPuller.downloadIndexFiles Skipping download for _0.fdt because it already exists
[junit4] 2> ASYNC NEW_CORE C2415 name=collection1 org.apache.solr.core.SolrCore@435c451
[junit4] 2> 724979 T2694 C2415 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 724983 T2693 C2415 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.tim&command=filecontent&checksum=true&compression=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 724984 T2709 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 724986 T2709 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 724986 T2709 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 724986 T2709 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 724989 T2709 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 724991 T2709 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37ea7c32 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19614e89),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37ea7c32 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19614e89),segFN=segments_3,generation=3}
[junit4] 2> 724991 T2709 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 724992 T2709 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 724993 T2685 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-1375290757032/repeater/solr.xml
[junit4] 2> 724994 T2709 oass.SolrIndexSearcher.<init> Opening Searcher@ccf1bdc main
[junit4] 2> 724994 T2685 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/solr.xml does not exist, using default configuration
[junit4] 2> 724994 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ccf1bdc main{StandardDirectoryReader(segments_3:5:nrt _0(4.5):C1 _1(4.5):C1)}
[junit4] 2> 724995 T2709 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-1375290756821/slave/collection1/data/index.20130731191237009 [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-1375290756821/slave/collection1/data/index.20130731191237009;done=true>>]
[junit4] 2> 724995 T2709 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-1375290756821/slave/collection1/data/index.20130731191237009
[junit4] 2> 725003 T2685 oasc.CoreContainer.<init> New CoreContainer 2132102152
[junit4] 2> 725004 T2685 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/]
[junit4] 2> 725004 T2685 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 725005 T2685 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 725005 T2685 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 725006 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 725006 T2685 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 725007 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 725007 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 725008 T2685 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 725008 T2685 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 725008 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 725015 T2726 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/collection1
[junit4] 2> 725015 T2726 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/collection1/'
[junit4] 2> 725031 T2726 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 725040 T2726 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 725041 T2726 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 725043 T2726 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 725045 T2726 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 725045 T2726 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 725045 T2726 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757032/repeater/collection1/data/
[junit4] 2> 725046 T2726 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 725046 T2726 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-1375290757032/repeater/collection1/data
[junit4] 2> 725046 T2726 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-1375290757032/repeater/collection1/data/index/
[junit4] 2> 725046 T2726 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-1375290757032/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 725047 T2726 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-1375290757032/repeater/collection1/data/index
[junit4] 2> 725047 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6619bbf0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20709587),segFN=segments_1,generation=1}
[junit4] 2> 725048 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 725048 T2726 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 725049 T2726 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 725049 T2726 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 725049 T2726 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 725049 T2726 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 725049 T2726 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 725050 T2726 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 725051 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@18017826 main
[junit4] 2> 725051 T2726 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 725051 T2726 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 725052 T2726 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 725052 T2726 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 725052 T2726 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 725052 T2726 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 725052 T2726 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 725052 T2727 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18017826 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 725053 T2685 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 725053 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 725054 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2416 name=collection1 org.apache.solr.core.SolrCore@435c451
[junit4] 2> 725063 T2692 C2416 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2417 name=collection1 org.apache.solr.core.SolrCore@6078a477
[junit4] 2> 725064 T2722 C2417 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 725086 T2685 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2132102152
[junit4] 2> 725087 T2685 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6078a477
[junit4] 2> 725088 T2685 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> 725089 T2685 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 725089 T2685 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 725089 T2685 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 725090 T2685 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 725091 T2685 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 725091 T2685 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-1375290757032/repeater/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-1375290757032/repeater/collection1/data/index;done=false>>]
[junit4] 2> 725092 T2685 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-1375290757032/repeater/collection1/data/index
[junit4] 2> 725092 T2685 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-1375290757032/repeater/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-1375290757032/repeater/collection1/data;done=false>>]
[junit4] 2> 725093 T2685 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-1375290757032/repeater/collection1/data
[junit4] 2> 725093 T2685 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 725148 T2685 oas.SolrTestCaseJ4.tearDown ###Ending doTestDetails
[junit4] 2> 725169 T2685 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=32237011
[junit4] 2> 725170 T2685 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@435c451
[junit4] 2> 725171 T2685 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 725171 T2685 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 725172 T2685 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 725172 T2685 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 725174 T2685 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 725174 T2685 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 725175 T2685 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-1375290756710/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-1375290756710/master/collection1/data;done=false>>]
[junit4] 2> 725175 T2685 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-1375290756710/master/collection1/data
[junit4] 2> 725176 T2685 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-1375290756710/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-1375290756710/master/collection1/data/index;done=false>>]
[junit4] 2> 725176 T2685 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-1375290756710/master/collection1/data/index
[junit4] 2> 725177 T2685 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 725250 T2685 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=64384170
[junit4] 2> 725251 T2685 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@660bdfa6
[junit4] 2> 725252 T2685 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> 725253 T2685 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 725253 T2685 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 725254 T2685 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 725256 T2685 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 725256 T2685 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 725257 T2685 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-1375290756821/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-1375290756821/slave/collection1/data/index;done=false>>]
[junit4] 2> 725257 T2685 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-1375290756821/slave/collection1/data/index
[junit4] 2> 725258 T2685 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-1375290756821/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-1375290756821/slave/collection1/data;done=false>>]
[junit4] 2> 725258 T2685 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-1375290756821/slave/collection1/data
[junit4] 2> 725259 T2685 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 725323 T2685 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterWrite2Slave
[junit4] 2> 725328 T2685 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 725331 T2685 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58369
[junit4] 2> 725332 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 725332 T2685 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 725333 T2685 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master
[junit4] 2> 725333 T2685 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/'
[junit4] 2> 725348 T2685 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-1375290757402/master/solr.xml
[junit4] 2> 725348 T2685 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/solr.xml does not exist, using default configuration
[junit4] 2> 725358 T2685 oasc.CoreContainer.<init> New CoreContainer 2114566440
[junit4] 2> 725358 T2685 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/]
[junit4] 2> 725359 T2685 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 725359 T2685 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 725359 T2685 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 725359 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 725359 T2685 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 725360 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 725360 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 725360 T2685 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 725360 T2685 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 725361 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 725365 T2738 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/collection1
[junit4] 2> 725365 T2738 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/collection1/'
[junit4] 2> 725385 T2738 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 725390 T2738 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 725391 T2738 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 725393 T2738 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 725398 T2738 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 725398 T2738 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 725399 T2738 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757402/master/collection1/data/
[junit4] 2> 725399 T2738 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 725399 T2738 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-1375290757402/master/collection1/data
[junit4] 2> 725399 T2738 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-1375290757402/master/collection1/data/index/
[junit4] 2> 725399 T2738 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-1375290757402/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 725400 T2738 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-1375290757402/master/collection1/data/index
[junit4] 2> 725400 T2738 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@efba680 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44aee64f),segFN=segments_1,generation=1}
[junit4] 2> 725401 T2738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 725401 T2738 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 725401 T2738 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 725402 T2738 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 725402 T2738 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 725402 T2738 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 725402 T2738 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 725403 T2738 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 725404 T2738 oass.SolrIndexSearcher.<init> Opening Searcher@ede9900 main
[junit4] 2> 725404 T2738 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 725404 T2738 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 725405 T2738 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 725405 T2738 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 725405 T2738 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 725405 T2739 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ede9900 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 725406 T2685 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 725406 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 725407 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 725416 T2685 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 725419 T2685 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44880
[junit4] 2> 725421 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 725421 T2685 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 725422 T2685 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave
[junit4] 2> 725422 T2685 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/'
[junit4] 2> 725439 T2685 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-1375290757490/slave/solr.xml
[junit4] 2> 725440 T2685 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/solr.xml does not exist, using default configuration
[junit4] 2> 725447 T2685 oasc.CoreContainer.<init> New CoreContainer 433559207
[junit4] 2> 725447 T2685 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/]
[junit4] 2> 725447 T2685 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 725448 T2685 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 725448 T2685 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 725448 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 725448 T2685 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 725449 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 725449 T2685 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 725449 T2685 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 725449 T2685 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 725449 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 725454 T2750 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/collection1
[junit4] 2> 725454 T2750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/collection1/'
[junit4] 2> 725478 T2750 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 725487 T2750 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 725488 T2750 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 725492 T2750 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 725495 T2750 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 725496 T2750 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 725496 T2750 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1375290757490/slave/collection1/data/
[junit4] 2> 725497 T2750 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 725497 T2750 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-1375290757490/slave/collection1/data
[junit4] 2> 725498 T2750 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-1375290757490/slave/collection1/data/index/
[junit4] 2> 725498 T2750 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-1375290757490/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 725498 T2750 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-1375290757490/slave/collection1/data/index
[junit4] 2> 725500 T2750 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5bc574cb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41c96100),segFN=segments_1,generation=1}
[junit4] 2> 725500 T2750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 725502 T2750 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 725502 T2750 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 725503 T2750 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 725503 T2750 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 725503 T2750 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 725504 T2750 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 725504 T2750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 725505 T2750 oass.SolrIndexSearcher.<init> Opening Searcher@6cc7986d main
[junit4] 2> 725505 T2750 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 725505 T2750 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 725506 T2750 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 725506 T2751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6cc7986d main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 725506 T2750 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 725507 T2685 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 725507 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 725508 T2685 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C2418 name=collection1 org.apache.solr.core.SolrCore@53ca53aa
[junit4] 2> 725515 T2734 C2418 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 725520 T2735 C2418 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@efba680 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44aee64f),segFN=segments_1,generation=1}
[junit4] 2> 725521 T2735 C2418 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 725521 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 3
[junit4] 2> 725524 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 725527 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 725530 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4] 2> 725532 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 725536 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 1
[junit4] 2> 725539 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 725541 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 725544 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 725547 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4] 2> 725550 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 725552 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 725555 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 725558 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 1
[junit4] 2> 725560 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 725563 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 725566 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 725569 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 1
[junit4] 2> 725571 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 725574 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 725577 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 725579 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 725582 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 725585 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4] 2> 725587 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 725590 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 725593 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 1
[junit4] 2> 725595 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 725598 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 725601 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 1
[junit4] 2> 725603 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 725606 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 725608 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 725611 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 725614 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 1
[junit4] 2> 725617 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 1
[junit4] 2> 725619 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 725622 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 725625 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 725628 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 1
[junit4] 2> 725631 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 1
[junit4] 2> 725633 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 725636 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 725639 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 725642 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 1
[junit4] 2> 725645 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 1
[junit4] 2> 725647 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 725650 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 725653 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 725656 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4] 2> 725659 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 1
[junit4] 2> 725661 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 725664 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4] 2> 725667 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 725670 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 0
[junit4] 2> 725673 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 1
[junit4] 2> 725676 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 1
[junit4] 2> 725679 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 1
[junit4] 2> 725682 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 1
[junit4] 2> 725684 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4] 2> 725687 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 0
[junit4] 2> 725690 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4] 2> 725693 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4] 2> 725696 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4] 2> 725699 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 1
[junit4] 2> 725702 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 1
[junit4] 2> 725705 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 1
[junit4] 2> 725707 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 0
[junit4] 2> 725710 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 725713 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 725716 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4] 2> 725719 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 0
[junit4] 2> 725722 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 1
[junit4] 2> 725725 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 1
[junit4] 2> 725728 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 1
[junit4] 2> 725731 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 1
[junit4] 2> 725733 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 0
[junit4] 2> 725736 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4] 2> 725739 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 1
[junit4] 2> 725742 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 1
[junit4] 2> 725745 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 1
[junit4] 2> 725748 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 1
[junit4] 2> 725750 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4] 2> 725753 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 0
[junit4] 2> 725756 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 725759 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 1
[junit4] 2> 725762 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 1
[junit4] 2> 725765 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 1
[junit4] 2> 725768 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 1
[junit4] 2> 725770 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4] 2> 725773 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 0
[junit4] 2> 725776 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4] 2> 725779 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 725782 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4] 2> 725784 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4] 2> 725786 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4] 2> 725789 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 725791 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4] 2> 725793 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 0
[junit4] 2> 725795 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 0
[junit4] 2> 725797 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4] 2> 725799 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4] 2> 725801 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4] 2> 725803 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 725805 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 725808 T2737 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 1
[junit4] 2> 725810 T2734 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4] 2> 725812 T2736 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 0
[junit4] 2> 725815 T2735 C2418 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=java
[...truncated too long message...]
er.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 791079 T2685 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 791081 T3377 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 791082 T3377 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> ASYNC NEW_CORE C2538 name=collection1 org.apache.solr.core.SolrCore@8e9abd2
[junit4] 2> 791084 T3358 C2538 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2539 name=collection1 org.apache.solr.core.SolrCore@5010af52
[junit4] 2> 791085 T3369 C2539 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:58210/solr/replication&command=fetchindex&wait=true} status=0 QTime=4
[junit4] 2> 791086 T2685 oas.SolrTestCaseJ4.tearDown ###Ending testNoWriter
[junit4] 2> 791107 T2685 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1950693446
[junit4] 2> 791107 T2685 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8e9abd2
[junit4] 2> 791108 T2685 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> 791108 T2685 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 791108 T2685 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 791108 T2685 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 791109 T2685 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 791110 T2685 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 791110 T2685 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-1375290822493/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-1375290822493/master/collection1/data;done=false>>]
[junit4] 2> 791110 T2685 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-1375290822493/master/collection1/data
[junit4] 2> 791111 T2685 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-1375290822493/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-1375290822493/master/collection1/data/index;done=false>>]
[junit4] 2> 791111 T2685 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-1375290822493/master/collection1/data/index
[junit4] 2> 791112 T2685 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 791184 T2685 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1312804448
[junit4] 2> 791184 T2685 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5010af52
[junit4] 2> 791184 T2685 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> 791185 T2685 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 791185 T2685 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 791185 T2685 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 791185 T2685 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 791186 T2685 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 791187 T2685 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-1375290822562/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-1375290822562/slave/collection1/data/index;done=false>>]
[junit4] 2> 791187 T2685 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-1375290822562/slave/collection1/data/index
[junit4] 2> 791187 T2685 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-1375290822562/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-1375290822562/slave/collection1/data;done=false>>]
[junit4] 2> 791187 T2685 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-1375290822562/slave/collection1/data
[junit4] 2> 791188 T2685 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 791246 T2685 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Appending, sim=DefaultSimilarity, locale=sq_AL, timezone=Europe/Sarajevo
[junit4] 2> NOTE: Linux 3.2.0-51-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=128156536,total=277778432
[junit4] 2> NOTE: All tests run in this JVM: [SolrInfoMBeanTest, TestPhraseSuggestions, TestStressVersions, TestSolrXml, TestXIncludeConfig, DistributedQueryElevationComponentTest, SliceStateTest, RecoveryZkTest, WordBreakSolrSpellCheckerTest, BlockDirectoryTest, TestIBSimilarityFactory, RegexBoostProcessorTest, QueryEqualityTest, InfoHandlerTest, SpatialFilterTest, TestFaceting, SearchHandlerTest, TestUniqueKeyFieldResource, TestJoin, SchemaVersionSpecificBehaviorTest, TestLMDirichletSimilarityFactory, UUIDFieldTest, TestSurroundQueryParser, LegacyHTMLStripCharFilterTest, HdfsDirectoryTest, ZkControllerTest, TestSolrQueryParserDefaultOperatorResource, TestSearchPerf, TestPerFieldSimilarity, TestRemoteStreaming, DistributedTermsComponentTest, TestFastWriter, ResponseLogComponentTest, MBeansHandlerTest, QueryElevationComponentTest, TestSolrQueryParser, TestStressRecovery, IndexSchemaTest, HdfsBasicDistributedZkTest, TestLRUCache, OverseerCollectionProcessorTest, TestValueSourceCache, StressHdfsTest, CircularListTest, CoreAdminHandlerTest, TestQuerySenderListener, TestReversedWildcardFilterFactory, SimpleFacetsTest, TestFuzzyAnalyzedSuggestions, LeaderElectionTest, ClusterStateUpdateTest, TestFieldTypeCollectionResource, TestCharFilters, TestSolrIndexConfig, TestBadConfig, TermsComponentTest, TestFieldTypeResource, ReturnFieldsTest, TestShardHandlerFactory, TestDocumentBuilder, TestTrie, PrimitiveFieldTypeTest, TestConfig, PrimUtilsTest, TestLFUCache, TestQueryUtils, SolrTestCaseJ4Test, SolrCoreTest, DirectSolrConnectionTest, TestMaxScoreQueryParser, ChaosMonkeyNothingIsSafeTest, LukeRequestHandlerTest, OverseerTest, ZkCLITest, UnloadDistributedZkTest, BasicDistributedZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed on J0 in 66.65s, 14 tests, 1 failure <<< FAILURES!
[...truncated 462 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: 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:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 321 suites, 1350 tests, 1 failure, 37 ignored (6 assumptions)
Total time: 35 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure