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 2014/05/28 21:35:59 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_60-ea-b15) - Build
# 10294 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10294/
Java: 64bit/jdk1.7.0_60-ea-b15 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Could not remove the following files (in the order of attempts): /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304499332/master/collection1/data/snapshot.rkbjxbzdedexut/_0_nrm.cfs /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304499332/master/collection1/data/snapshot.rkbjxbzdedexut/_0_Lucene41_0.tim /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304499332/master/collection1/data/snapshot.rkbjxbzdedexut
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304499332/master/collection1/data/snapshot.rkbjxbzdedexut/_0_nrm.cfs
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304499332/master/collection1/data/snapshot.rkbjxbzdedexut/_0_Lucene41_0.tim
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304499332/master/collection1/data/snapshot.rkbjxbzdedexut
at __randomizedtesting.SeedInfo.seed([F9BA3DC91BDBC03E:B8311DAC3C653371]:0)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1554)
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:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10936 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-F9BA3DC91BDBC03E-001/init-core-data-001
[junit4] 2> 294685 T1772 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartup
[junit4] 2> 294689 T1772 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 294694 T1772 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58021
[junit4] 2> 294694 T1772 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 294695 T1772 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 294695 T1772 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master
[junit4] 2> 294695 T1772 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/'
[junit4] 2> 294705 T1772 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-1401304463718/master/solr.xml
[junit4] 2> 294727 T1772 oasc.CoreContainer.<init> New CoreContainer 2078197520
[junit4] 2> 294728 T1772 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/]
[junit4] 2> 294728 T1772 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 294729 T1772 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 294729 T1772 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 294729 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 294729 T1772 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 294729 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 294730 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 294730 T1772 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 294730 T1772 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 294730 T1772 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 294731 T1772 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 294731 T1772 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 294738 T1782 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/'
[junit4] 2> 294769 T1782 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 294786 T1782 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 294786 T1782 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 294789 T1782 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 294795 T1782 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 294795 T1782 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/
[junit4] 2> 294795 T1782 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 294796 T1782 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/data/
[junit4] 2> 294796 T1782 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 294796 T1782 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-1401304463718/master/collection1/data
[junit4] 2> 294796 T1782 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-1401304463718/master/collection1/data/index/
[junit4] 2> 294796 T1782 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-1401304463718/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 294797 T1782 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-1401304463718/master/collection1/data/index
[junit4] 2> 294797 T1782 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=78.3955078125, floorSegmentMB=0.8916015625, forceMergeDeletesPctAllowed=14.396813874489832, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 294798 T1782 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@32fd2a7f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78766453),segFN=segments_1,generation=1}
[junit4] 2> 294798 T1782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 294798 T1782 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 294799 T1782 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 294799 T1782 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 294799 T1782 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 294799 T1782 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 294799 T1782 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 294799 T1782 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 294801 T1782 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 294802 T1782 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 294802 T1782 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 294803 T1782 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=76.005859375, floorSegmentMB=0.8037109375, forceMergeDeletesPctAllowed=28.4508943734684, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1838437778533235
[junit4] 2> 294803 T1782 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@32fd2a7f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78766453),segFN=segments_1,generation=1}
[junit4] 2> 294803 T1782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 294803 T1782 oass.SolrIndexSearcher.<init> Opening Searcher@7a0aa964[collection1] main
[junit4] 2> 294804 T1782 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/conf
[junit4] 2> 294804 T1782 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/conf/}
[junit4] 2> 294804 T1782 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/conf
[junit4] 2> 294804 T1782 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 294804 T1782 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 294804 T1782 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 294805 T1782 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 294805 T1782 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 294805 T1783 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a0aa964[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 294805 T1782 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 294805 T1772 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 294806 T1772 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 294811 T1772 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 294815 T1772 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53828
[junit4] 2> 294816 T1772 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 294816 T1772 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 294817 T1772 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave
[junit4] 2> 294817 T1772 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/'
[junit4] 2> 294833 T1772 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-1401304463839/slave/solr.xml
[junit4] 2> 294854 T1772 oasc.CoreContainer.<init> New CoreContainer 764218763
[junit4] 2> 294854 T1772 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/]
[junit4] 2> 294855 T1772 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 294855 T1772 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 294855 T1772 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 294856 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 294856 T1772 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 294856 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 294856 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 294856 T1772 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 294856 T1772 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 294857 T1772 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 294857 T1772 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 294857 T1772 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 294859 T1794 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/'
[junit4] 2> 294889 T1794 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 294906 T1794 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 294906 T1794 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 294909 T1794 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 294914 T1794 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 294915 T1794 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/
[junit4] 2> 294915 T1794 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 294915 T1794 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/data/
[junit4] 2> 294915 T1794 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 294916 T1794 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-1401304463839/slave/collection1/data
[junit4] 2> 294916 T1794 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-1401304463839/slave/collection1/data/index/
[junit4] 2> 294916 T1794 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-1401304463839/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 294917 T1794 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-1401304463839/slave/collection1/data/index
[junit4] 2> 294917 T1794 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=78.3955078125, floorSegmentMB=0.8916015625, forceMergeDeletesPctAllowed=14.396813874489832, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 294917 T1794 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24de722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@465f7fa8),segFN=segments_1,generation=1}
[junit4] 2> 294917 T1794 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 294918 T1794 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 294918 T1794 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 294919 T1794 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 294919 T1794 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 294919 T1794 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 294919 T1794 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 294919 T1794 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 294920 T1794 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 294921 T1794 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 294921 T1794 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 294921 T1794 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=76.005859375, floorSegmentMB=0.8037109375, forceMergeDeletesPctAllowed=28.4508943734684, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1838437778533235
[junit4] 2> 294922 T1794 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24de722 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@465f7fa8),segFN=segments_1,generation=1}
[junit4] 2> 294922 T1794 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 294922 T1794 oass.SolrIndexSearcher.<init> Opening Searcher@3d7bf758[collection1] main
[junit4] 2> 294922 T1794 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/conf
[junit4] 2> 294922 T1794 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/conf/}
[junit4] 2> 294923 T1794 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463839/slave/collection1/conf
[junit4] 2> 294923 T1794 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 294923 T1794 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 294923 T1794 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 294924 T1794 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 294925 T1794 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 294925 T1795 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d7bf758[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 294925 T1772 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 294926 T1772 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 294950 T1772 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=764218763
[junit4] 2> 294951 T1772 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f48cda2
[junit4] 2> 294952 T1772 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> 294952 T1772 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 294953 T1772 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 294953 T1772 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 294955 T1772 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 294955 T1772 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 294956 T1772 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-1401304463839/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-1401304463839/slave/collection1/data;done=false>>]
[junit4] 2> 294956 T1772 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-1401304463839/slave/collection1/data
[junit4] 2> 294957 T1772 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-1401304463839/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-1401304463839/slave/collection1/data/index;done=false>>]
[junit4] 2> 294957 T1772 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-1401304463839/slave/collection1/data/index
[junit4] 2> 294958 T1772 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> ASYNC NEW_CORE C558 name=collection1 org.apache.solr.core.SolrCore@67d61fa1
[junit4] 2> 295012 T1778 C558 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 295013 T1778 C558 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 295017 T1779 C558 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 295018 T1779 C558 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@32fd2a7f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78766453),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@32fd2a7f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78766453),segFN=segments_2,generation=2}
[junit4] 2> 295019 T1779 C558 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 295020 T1779 C558 oass.SolrIndexSearcher.<init> Opening Searcher@453ce76b[collection1] main
[junit4] 2> 295021 T1779 C558 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 295021 T1783 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@453ce76b[collection1] main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 295021 T1779 C558 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&wt=javabin&version=2&waitSearcher=true} {commit=} 0 5
[junit4] 2> 295047 T1772 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2078197520
[junit4] 2> 295048 T1772 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@67d61fa1
[junit4] 2> 295049 T1772 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 295050 T1772 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 295050 T1772 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 295051 T1772 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 295052 T1772 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 295052 T1772 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 295053 T1772 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-1401304463718/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-1401304463718/master/collection1/data;done=false>>]
[junit4] 2> 295053 T1772 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-1401304463718/master/collection1/data
[junit4] 2> 295054 T1772 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-1401304463718/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-1401304463718/master/collection1/data/index;done=false>>]
[junit4] 2> 295055 T1772 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-1401304463718/master/collection1/data/index
[junit4] 2> 295056 T1772 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 295108 T1772 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 295112 T1772 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59873
[junit4] 2> 295114 T1772 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 295114 T1772 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 295115 T1772 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master
[junit4] 2> 295115 T1772 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/'
[junit4] 2> 295124 T1772 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-1401304463718/master/solr.xml
[junit4] 2> 295143 T1772 oasc.CoreContainer.<init> New CoreContainer 1625951664
[junit4] 2> 295143 T1772 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/]
[junit4] 2> 295144 T1772 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 295144 T1772 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 295144 T1772 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 295144 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 295144 T1772 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 295145 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 295145 T1772 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 295145 T1772 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 295145 T1772 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 295146 T1772 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 295146 T1772 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 295146 T1772 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 295148 T1807 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/'
[junit4] 2> 295184 T1807 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 295209 T1807 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 295210 T1807 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 295213 T1807 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 295219 T1807 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 295219 T1807 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/
[junit4] 2> 295219 T1807 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 295220 T1807 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/data/
[junit4] 2> 295220 T1807 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 295220 T1807 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-1401304463718/master/collection1/data
[junit4] 2> 295220 T1807 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-1401304463718/master/collection1/data/index/
[junit4] 2> 295220 T1807 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-1401304463718/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 295221 T1807 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-1401304463718/master/collection1/data/index
[junit4] 2> 295221 T1807 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=78.3955078125, floorSegmentMB=0.8916015625, forceMergeDeletesPctAllowed=14.396813874489832, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 295222 T1807 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf49ded lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77ef0860),segFN=segments_1,generation=1}
[junit4] 2> 295222 T1807 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 295222 T1807 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 295223 T1807 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 295223 T1807 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 295223 T1807 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 295223 T1807 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 295223 T1807 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 295223 T1807 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 295224 T1807 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 295225 T1807 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 295226 T1807 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 295226 T1807 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=76.005859375, floorSegmentMB=0.8037109375, forceMergeDeletesPctAllowed=28.4508943734684, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1838437778533235
[junit4] 2> 295227 T1807 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf49ded lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77ef0860),segFN=segments_1,generation=1}
[junit4] 2> 295227 T1807 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 295227 T1807 oass.SolrIndexSearcher.<init> Opening Searcher@46d572ed[collection1] main
[junit4] 2> 295228 T1807 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/conf
[junit4] 2> 295228 T1807 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/conf/}
[junit4] 2> 295229 T1807 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401304463718/master/collection1/conf
[junit4] 2> 295229 T1807 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 295229 T1807 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 295230 T1807 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 295230 T1807 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 295230 T1807 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 295231 T1807 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 295231 T1808 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@46d572ed[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 295232 T1772 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 295232 T1772 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C559 name=collection1 org.apache.solr.core.SolrCore@64e984c4
[junit4] 2> 295235 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 295239 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 295242 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 295245 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 295248 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 295251 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 295254 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 295257 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 295260 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 295263 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 295266 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 295269 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 295272 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 295275 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 295278 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 1
[junit4] 2> 295281 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4] 2> 295284 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 1
[junit4] 2> 295286 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 295289 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 295292 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 295295 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 295298 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 295301 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 295304 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4] 2> 295307 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4] 2> 295310 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4] 2> 295313 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 1
[junit4] 2> 295315 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 295319 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 1
[junit4] 2> 295322 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 295325 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 295328 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 295332 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 1
[junit4] 2> 295335 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 1
[junit4] 2> 295338 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 1
[junit4] 2> 295341 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 295344 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 295347 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 295350 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 295353 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 295356 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 295359 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 1
[junit4] 2> 295362 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 295365 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 295368 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 1
[junit4] 2> 295371 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 1
[junit4] 2> 295374 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 1
[junit4] 2> 295376 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 295379 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 295383 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4] 2> 295385 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4] 2> 295388 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 295391 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4] 2> 295394 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 295397 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 1
[junit4] 2> 295399 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4] 2> 295402 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4] 2> 295405 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4] 2> 295408 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4] 2> 295411 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 0
[junit4] 2> 295414 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 1
[junit4] 2> 295416 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4] 2> 295419 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 0
[junit4] 2> 295422 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 0
[junit4] 2> 295425 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 0
[junit4] 2> 295428 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 1
[junit4] 2> 295430 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4] 2> 295433 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 0
[junit4] 2> 295436 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 295439 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 295442 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 0
[junit4] 2> 295445 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 1
[junit4] 2> 295448 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 1
[junit4] 2> 295450 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 0
[junit4] 2> 295453 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4] 2> 295456 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4] 2> 295459 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 0
[junit4] 2> 295462 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 1
[junit4] 2> 295465 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 1
[junit4] 2> 295467 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 0
[junit4] 2> 295470 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 0
[junit4] 2> 295473 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 0
[junit4] 2> 295476 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 0
[junit4] 2> 295479 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 1
[junit4] 2> 295481 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 295484 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4] 2> 295487 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 1
[junit4] 2> 295489 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4] 2> 295492 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4] 2> 295495 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 0
[junit4] 2> 295498 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 1
[junit4] 2> 295500 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4] 2> 295503 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 295506 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4] 2> 295509 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 1
[junit4] 2> 295511 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4] 2> 295514 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 295517 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4] 2> 295520 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 0
[junit4] 2> 295523 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 1
[junit4] 2> 295525 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4] 2> 295528 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 0
[junit4] 2> 295531 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4] 2> 295534 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 295537 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 295540 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4] 2> 295543 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 1
[junit4] 2> 295546 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 1
[junit4] 2> 295548 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 0
[junit4] 2> 295551 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4] 2> 295554 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4] 2> 295557 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4] 2> 295560 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 1
[junit4] 2> 295563 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 1
[junit4] 2> 295565 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 295568 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 0
[junit4] 2> 295571 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 0
[junit4] 2> 295574 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 0
[junit4] 2> 295577 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 0
[junit4] 2> 295580 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 295583 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4] 2> 295586 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 1
[junit4] 2> 295589 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 1
[junit4] 2> 295591 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 295594 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 295597 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 0
[junit4] 2> 295600 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 0
[junit4] 2> 295603 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 1
[junit4] 2> 295606 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4] 2> 295609 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4] 2> 295612 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 295615 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4] 2> 295618 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4] 2> 295620 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 0
[junit4] 2> 295623 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 0
[junit4] 2> 295626 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4] 2> 295629 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 0
[junit4] 2> 295632 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4] 2> 295635 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 1
[junit4] 2> 295637 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4] 2> 295640 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 0
[junit4] 2> 295643 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 0
[junit4] 2> 295646 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4] 2> 295649 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4] 2> 295652 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 295655 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 1
[junit4] 2> 295657 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4] 2> 295660 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4] 2> 295663 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 0
[junit4] 2> 295666 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4] 2> 295669 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[150]} 0 0
[junit4] 2> 295672 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[151]} 0 0
[junit4] 2> 295675 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[152]} 0 1
[junit4] 2> 295677 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[153]} 0 0
[junit4] 2> 295680 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[154]} 0 0
[junit4] 2> 295683 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[155]} 0 0
[junit4] 2> 295686 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[156]} 0 0
[junit4] 2> 295689 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[157]} 0 1
[junit4] 2> 295691 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[158]} 0 0
[junit4] 2> 295694 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[159]} 0 0
[junit4] 2> 295697 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[160]} 0 1
[junit4] 2> 295699 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[161]} 0 0
[junit4] 2> 295702 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[162]} 0 0
[junit4] 2> 295705 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[163]} 0 0
[junit4] 2> 295708 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[164]} 0 1
[junit4] 2> 295710 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[165]} 0 0
[junit4] 2> 295713 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[166]} 0 0
[junit4] 2> 295716 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[167]} 0 0
[junit4] 2> 295719 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[168]} 0 1
[junit4] 2> 295722 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[169]} 0 1
[junit4] 2> 295725 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[170]} 0 1
[junit4] 2> 295727 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[171]} 0 0
[junit4] 2> 295730 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[172]} 0 0
[junit4] 2> 295733 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[173]} 0 0
[junit4] 2> 295736 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[174]} 0 0
[junit4] 2> 295739 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[175]} 0 0
[junit4] 2> 295742 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[176]} 0 1
[junit4] 2> 295745 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[177]} 0 0
[junit4] 2> 295747 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[178]} 0 0
[junit4] 2> 295750 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[179]} 0 0
[junit4] 2> 295753 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[180]} 0 0
[junit4] 2> 295756 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[181]} 0 0
[junit4] 2> 295759 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[182]} 0 0
[junit4] 2> 295762 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[183]} 0 1
[junit4] 2> 295764 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[184]} 0 0
[junit4] 2> 295767 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[185]} 0 0
[junit4] 2> 295770 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[186]} 0 0
[junit4] 2> 295773 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[187]} 0 0
[junit4] 2> 295776 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[188]} 0 0
[junit4] 2> 295779 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[189]} 0 0
[junit4] 2> 295782 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[190]} 0 1
[junit4] 2> 295785 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[191]} 0 1
[junit4] 2> 295788 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[192]} 0 1
[junit4] 2> 295790 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[193]} 0 0
[junit4] 2> 295793 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[194]} 0 0
[junit4] 2> 295796 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[195]} 0 0
[junit4] 2> 295799 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[196]} 0 0
[junit4] 2> 295802 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[197]} 0 1
[junit4] 2> 295804 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[198]} 0 0
[junit4] 2> 295807 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[199]} 0 0
[junit4] 2> 295810 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[200]} 0 0
[junit4] 2> 295813 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[201]} 0 0
[junit4] 2> 295815 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[202]} 0 0
[junit4] 2> 295818 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[203]} 0 0
[junit4] 2> 295821 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[204]} 0 0
[junit4] 2> 295824 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[205]} 0 0
[junit4] 2> 295827 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[206]} 0 1
[junit4] 2> 295829 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[207]} 0 0
[junit4] 2> 295832 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[208]} 0 0
[junit4] 2> 295835 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[209]} 0 0
[junit4] 2> 295837 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[210]} 0 0
[junit4] 2> 295840 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[211]} 0 0
[junit4] 2> 295843 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[212]} 0 0
[junit4] 2> 295846 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[213]} 0 0
[junit4] 2> 295849 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[214]} 0 1
[junit4] 2> 295851 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[215]} 0 0
[junit4] 2> 295854 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[216]} 0 0
[junit4] 2> 295857 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[217]} 0 0
[junit4] 2> 295860 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[218]} 0 1
[junit4] 2> 295862 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[219]} 0 0
[junit4] 2> 295865 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[220]} 0 0
[junit4] 2> 295868 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[221]} 0 0
[junit4] 2> 295870 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222]} 0 0
[junit4] 2> 295873 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[223]} 0 0
[junit4] 2> 295876 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[224]} 0 0
[junit4] 2> 295879 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[225]} 0 1
[junit4] 2> 295881 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[226]} 0 0
[junit4] 2> 295884 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[227]} 0 0
[junit4] 2> 295887 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[228]} 0 0
[junit4] 2> 295890 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[229]} 0 1
[junit4] 2> 295892 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[230]} 0 0
[junit4] 2> 295895 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[231]} 0 0
[junit4] 2> 295898 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[232]} 0 1
[junit4] 2> 295901 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[233]} 0 1
[junit4] 2> 295903 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[234]} 0 0
[junit4] 2> 295906 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[235]} 0 0
[junit4] 2> 295909 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[236]} 0 0
[junit4] 2> 295912 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[237]} 0 1
[junit4] 2> 295915 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[238]} 0 1
[junit4] 2> 295917 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[239]} 0 0
[junit4] 2> 295920 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[240]} 0 0
[junit4] 2> 295923 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[241]} 0 0
[junit4] 2> 295926 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[242]} 0 1
[junit4] 2> 295929 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[243]} 0 1
[junit4] 2> 295931 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[244]} 0 0
[junit4] 2> 295934 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[245]} 0 0
[junit4] 2> 295937 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[246]} 0 0
[junit4] 2> 295940 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[247]} 0 1
[junit4] 2> 295942 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[248]} 0 0
[junit4] 2> 295945 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[249]} 0 0
[junit4] 2> 295948 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[250]} 0 0
[junit4] 2> 295951 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[251]} 0 1
[junit4] 2> 295953 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[252]} 0 0
[junit4] 2> 295956 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[253]} 0 0
[junit4] 2> 295959 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[254]} 0 0
[junit4] 2> 295962 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[255]} 0 1
[junit4] 2> 295965 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[256]} 0 0
[junit4] 2> 295968 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[257]} 0 0
[junit4] 2> 295970 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[258]} 0 0
[junit4] 2> 295973 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[259]} 0 0
[junit4] 2> 295976 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[260]} 0 0
[junit4] 2> 295979 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[261]} 0 1
[junit4] 2> 295981 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[262]} 0 0
[junit4] 2> 295984 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[263]} 0 0
[junit4] 2> 295987 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[264]} 0 0
[junit4] 2> 295990 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[265]} 0 1
[junit4] 2> 295992 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[266]} 0 0
[junit4] 2> 295995 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[267]} 0 0
[junit4] 2> 295998 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[268]} 0 1
[junit4] 2> 296000 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[269]} 0 0
[junit4] 2> 296003 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[270]} 0 0
[junit4] 2> 296006 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[271]} 0 1
[junit4] 2> 296008 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[272]} 0 0
[junit4] 2> 296011 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[273]} 0 0
[junit4] 2> 296014 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[274]} 0 1
[junit4] 2> 296016 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[275]} 0 0
[junit4] 2> 296019 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[276]} 0 0
[junit4] 2> 296022 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[277]} 0 1
[junit4] 2> 296024 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[278]} 0 0
[junit4] 2> 296027 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[279]} 0 0
[junit4] 2> 296030 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[280]} 0 1
[junit4] 2> 296032 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[281]} 0 0
[junit4] 2> 296035 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[282]} 0 0
[junit4] 2> 296038 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[283]} 0 1
[junit4] 2> 296040 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[284]} 0 0
[junit4] 2> 296043 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[285]} 0 0
[junit4] 2> 296046 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[286]} 0 1
[junit4] 2> 296048 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[287]} 0 0
[junit4] 2> 296051 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[288]} 0 0
[junit4] 2> 296054 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[289]} 0 1
[junit4] 2> 296056 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[290]} 0 0
[junit4] 2> 296059 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[291]} 0 0
[junit4] 2> 296062 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[292]} 0 1
[junit4] 2> 296064 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[293]} 0 0
[junit4] 2> 296067 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[294]} 0 0
[junit4] 2> 296070 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[295]} 0 1
[junit4] 2> 296072 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[296]} 0 0
[junit4] 2> 296075 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[297]} 0 0
[junit4] 2> 296078 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[298]} 0 1
[junit4] 2> 296080 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[299]} 0 0
[junit4] 2> 296083 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[300]} 0 0
[junit4] 2> 296086 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[301]} 0 1
[junit4] 2> 296088 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[302]} 0 0
[junit4] 2> 296091 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[303]} 0 0
[junit4] 2> 296094 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[304]} 0 1
[junit4] 2> 296096 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[305]} 0 0
[junit4] 2> 296099 T1804 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[306]} 0 0
[junit4] 2> 296102 T1806 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[307]} 0 1
[junit4] 2> 296104 T1803 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[308]} 0 0
[junit4] 2> 296107 T1805 C559 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[309]} 0 0
[j
[...truncated too long message...]
r@3cfaee5[collection1] main
[junit4] 2> 337682 T2456 C614 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 337682 T2459 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cfaee5[collection1] main{StandardDirectoryReader(segments_6:11:nrt _0(4.9):C1 _1(4.9):C1 _2(4.9):C1 _3(4.9):C1 _4(4.9):C1)}
[junit4] 2> 337682 T2456 C614 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={softCommit=false&commit=true&wt=javabin&version=2&waitSearcher=true} {commit=} 0 3
[junit4] 2> 337685 T2457 C614 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&wt=javabin&version=2&q=id:555} hits=1 status=0 QTime=1
[junit4] 2> 337686 T2444 C613 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=enablereplication} status=0 QTime=1
[junit4] 2> 337687 T2454 C614 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=fetchindex} status=0 QTime=1
[junit4] 2> 337687 T1772 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterWrite2Slave
[junit4] 2> 337698 T1772 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1825320905
[junit4] 2> 337698 T2462 oahic.DefaultRequestDirector.tryExecute I/O exception (java.net.SocketException) caught when processing request: Connection reset
[junit4] 2> 337699 T2462 oahic.DefaultRequestDirector.tryExecute Retrying request
[junit4] 2> 337698 T1772 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@71855660
[junit4] 2> 337700 T1772 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=492,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 337700 T2462 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:52923/solr is not available. Index fetch failed. Exception: Server refused connection at: http://127.0.0.1:52923/solr
[junit4] 2> 337700 T1772 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 337700 T1772 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 337701 T1772 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 337702 T1772 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 337702 T1772 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 337702 T1772 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-1401304505277/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-1401304505277/master/collection1/data/index;done=false>>]
[junit4] 2> 337702 T1772 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-1401304505277/master/collection1/data/index
[junit4] 2> 337703 T1772 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-1401304505277/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-1401304505277/master/collection1/data;done=false>>]
[junit4] 2> 337703 T1772 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-1401304505277/master/collection1/data
[junit4] 2> 337703 T1772 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 337776 T1772 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1424772018
[junit4] 2> 337776 T1772 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@276cb2f8
[junit4] 2> 337777 T1772 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 337777 T1772 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 337777 T1772 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 337777 T1772 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 337778 T1772 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 337778 T1772 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 337778 T1772 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-1401304505405/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-1401304505405/slave/collection1/data;done=false>>]
[junit4] 2> 337778 T1772 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-1401304505405/slave/collection1/data
[junit4] 2> 337779 T1772 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-1401304505405/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-1401304505405/slave/collection1/data/index;done=false>>]
[junit4] 2> 337779 T1772 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-1401304505405/slave/collection1/data/index
[junit4] 2> 337779 T1772 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 337834 T1772 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-F9BA3DC91BDBC03E-001
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=sl_SI, timezone=America/Guayaquil
[junit4] 2> NOTE: Linux 3.8.0-39-generic amd64/Oracle Corporation 1.7.0_60-ea (64-bit)/cpus=8,threads=1,free=249995176,total=527958016
[junit4] 2> NOTE: All tests run in this JVM: [OverseerTest, TestNonNRTOpen, FieldMutatingUpdateProcessorTest, TestRemoteStreaming, CircularListTest, LegacyHTMLStripCharFilterTest, SystemInfoHandlerTest, DistribCursorPagingTest, CoreAdminRequestStatusTest, SolrCoreTest, ExternalFileFieldSortTest, TestSearchPerf, TestSort, TestImplicitCoreProperties, QueryParsingTest, LoggingHandlerTest, ZkCLITest, IndexSchemaTest, DocValuesTest, TestPerFieldSimilarity, PolyFieldTest, LeaderElectionTest, DistributedQueryComponentCustomSortTest, DateMathParserTest, TestRequestStatusCollectionAPI, TestCloudInspectUtil, TestRandomDVFaceting, DocumentBuilderTest, TestBlendedInfixSuggestions, CoreAdminHandlerTest, TestFaceting, HdfsBasicDistributedZk2Test, TestSolrXMLSerializer, ClusterStateUpdateTest, UpdateRequestProcessorFactoryTest, HdfsUnloadDistributedZkTest, TestDocumentBuilder, TestReplicationHandler]
[junit4] Completed on J0 in 43.20s, 14 tests, 1 error <<< FAILURES!
[...truncated 1074 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1297: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:921: There were test failures: 396 suites, 1640 tests, 1 error, 36 ignored (15 assumptions)
Total time: 58 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_60-ea-b15 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_60-ea-b15) - Build
# 10295 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10295/
Java: 32bit/jdk1.7.0_60-ea-b15 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Could not remove the following files (in the order of attempts): /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312925242/master/collection1/data/snapshot.lyloihditnoavc/_0.fnm /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312925242/master/collection1/data/snapshot.lyloihditnoavc
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312925242/master/collection1/data/snapshot.lyloihditnoavc/_0.fnm
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312925242/master/collection1/data/snapshot.lyloihditnoavc
at __randomizedtesting.SeedInfo.seed([F168D4CC528CAE9D:B0E3F4A975325DD2]:0)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1554)
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:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11261 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.handler.TestReplicationHandler-F168D4CC528CAE9D-001/init-core-data-001
[junit4] 2> 948451 T4362 oas.SolrTestCaseJ4.setUp ###Starting doTestRepeater
[junit4] 2> 948455 T4362 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 948458 T4362 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49416
[junit4] 2> 948458 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 948458 T4362 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 948459 T4362 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master
[junit4] 2> 948459 T4362 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/'
[junit4] 2> 948470 T4362 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/solr.xml
[junit4] 2> 948493 T4362 oasc.CoreContainer.<init> New CoreContainer 24576732
[junit4] 2> 948494 T4362 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/]
[junit4] 2> 948494 T4362 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 948495 T4362 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 948495 T4362 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 948495 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 948495 T4362 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 948496 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 948496 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 948496 T4362 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 948496 T4362 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 948497 T4362 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 948497 T4362 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 948497 T4362 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 948500 T4372 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/'
[junit4] 2> 948522 T4372 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 948550 T4372 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 948550 T4372 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 948553 T4372 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 948559 T4372 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 948560 T4372 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/
[junit4] 2> 948560 T4372 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 948560 T4372 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/data/
[junit4] 2> 948560 T4372 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 948561 T4372 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/data
[junit4] 2> 948561 T4372 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/data/index/
[junit4] 2> 948561 T4372 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 948562 T4372 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/data/index
[junit4] 2> 948562 T4372 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=5.0244140625, floorSegmentMB=1.4697265625, forceMergeDeletesPctAllowed=14.336040805130917, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 948562 T4372 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62018b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f2d15d),segFN=segments_1,generation=1}
[junit4] 2> 948563 T4372 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 948563 T4372 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 948563 T4372 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 948564 T4372 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 948564 T4372 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 948564 T4372 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 948564 T4372 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 948564 T4372 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 948566 T4372 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 948568 T4372 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 948568 T4372 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 948568 T4372 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=22.0595703125, floorSegmentMB=1.041015625, forceMergeDeletesPctAllowed=7.125655597650392, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 948569 T4372 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62018b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f2d15d),segFN=segments_1,generation=1}
[junit4] 2> 948569 T4372 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 948569 T4372 oass.SolrIndexSearcher.<init> Opening Searcher@1e14f8a[collection1] main
[junit4] 2> 948569 T4372 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/conf
[junit4] 2> 948569 T4372 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/conf/}
[junit4] 2> 948570 T4372 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312914932/master/collection1/conf
[junit4] 2> 948570 T4372 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 948570 T4372 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 948570 T4372 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 948571 T4372 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 948571 T4372 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 948571 T4372 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 948571 T4373 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e14f8a[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 948571 T4362 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 948572 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 948575 T4362 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 948576 T4362 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58381
[junit4] 2> 948577 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 948578 T4362 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 948578 T4362 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave
[junit4] 2> 948578 T4362 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/'
[junit4] 2> 948586 T4362 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/solr.xml
[junit4] 2> 948607 T4362 oasc.CoreContainer.<init> New CoreContainer 31151601
[junit4] 2> 948607 T4362 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/]
[junit4] 2> 948608 T4362 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 948608 T4362 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 948608 T4362 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 948608 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 948609 T4362 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 948609 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 948609 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 948609 T4362 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 948610 T4362 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 948610 T4362 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 948610 T4362 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 948611 T4362 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 948613 T4384 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/'
[junit4] 2> 948635 T4384 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 948663 T4384 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 948664 T4384 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 948667 T4384 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 948672 T4384 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 948673 T4384 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/
[junit4] 2> 948673 T4384 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 948674 T4384 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/
[junit4] 2> 948674 T4384 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 948674 T4384 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data
[junit4] 2> 948674 T4384 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index/
[junit4] 2> 948675 T4384 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 948675 T4384 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index
[junit4] 2> 948675 T4384 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=5.0244140625, floorSegmentMB=1.4697265625, forceMergeDeletesPctAllowed=14.336040805130917, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 948676 T4384 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@183765 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@127b2da),segFN=segments_1,generation=1}
[junit4] 2> 948676 T4384 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 948676 T4384 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 948677 T4384 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 948677 T4384 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 948677 T4384 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 948677 T4384 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 948677 T4384 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 948678 T4384 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 948679 T4384 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 948680 T4384 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 948680 T4384 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 948680 T4384 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=22.0595703125, floorSegmentMB=1.041015625, forceMergeDeletesPctAllowed=7.125655597650392, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 948681 T4384 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@183765 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@127b2da),segFN=segments_1,generation=1}
[junit4] 2> 948681 T4384 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 948681 T4384 oass.SolrIndexSearcher.<init> Opening Searcher@16451d4[collection1] main
[junit4] 2> 948681 T4384 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/conf
[junit4] 2> 948682 T4384 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/conf/}
[junit4] 2> 948682 T4384 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/conf
[junit4] 2> 948682 T4384 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 948682 T4384 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 948682 T4384 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 948684 T4384 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 948684 T4384 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 948684 T4385 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16451d4[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 948684 T4362 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 948684 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 948706 T4362 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=31151601
[junit4] 2> 948707 T4362 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4358b0
[junit4] 2> 948708 T4362 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> 948708 T4362 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 948708 T4362 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 948708 T4362 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 948709 T4362 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 948710 T4362 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 948710 T4362 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data;done=false>>]
[junit4] 2> 948710 T4362 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data
[junit4] 2> 948711 T4362 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index;done=false>>]
[junit4] 2> 948711 T4362 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index
[junit4] 2> 948711 T4362 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 948765 T4362 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 948768 T4362 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:41382
[junit4] 2> 948769 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 948770 T4362 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 948770 T4362 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave
[junit4] 2> 948771 T4362 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/'
[junit4] 2> 948787 T4362 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/solr.xml
[junit4] 2> 948809 T4362 oasc.CoreContainer.<init> New CoreContainer 5000114
[junit4] 2> 948809 T4362 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/]
[junit4] 2> 948810 T4362 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 948810 T4362 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 948810 T4362 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 948811 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 948811 T4362 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 948811 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 948811 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 948812 T4362 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 948812 T4362 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 948812 T4362 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 948813 T4362 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 948813 T4362 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 948815 T4397 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/'
[junit4] 2> 948841 T4397 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 948879 T4397 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 948880 T4397 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 948886 T4397 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 948893 T4397 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 948894 T4397 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/
[junit4] 2> 948894 T4397 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 948894 T4397 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/
[junit4] 2> 948895 T4397 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 948895 T4397 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data
[junit4] 2> 948895 T4397 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index/
[junit4] 2> 948895 T4397 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 948896 T4397 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index
[junit4] 2> 948896 T4397 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=5.0244140625, floorSegmentMB=1.4697265625, forceMergeDeletesPctAllowed=14.336040805130917, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 948896 T4397 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ef7313 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65e491),segFN=segments_1,generation=1}
[junit4] 2> 948897 T4397 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 948897 T4397 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 948897 T4397 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 948898 T4397 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 948898 T4397 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 948898 T4397 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 948898 T4397 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 948898 T4397 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 948899 T4397 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 948900 T4397 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 948901 T4397 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 948901 T4397 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=22.0595703125, floorSegmentMB=1.041015625, forceMergeDeletesPctAllowed=7.125655597650392, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 948902 T4397 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ef7313 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65e491),segFN=segments_1,generation=1}
[junit4] 2> 948902 T4397 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 948903 T4397 oass.SolrIndexSearcher.<init> Opening Searcher@1daa20c[collection1] main
[junit4] 2> 948903 T4397 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/conf
[junit4] 2> 948904 T4397 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/conf/}
[junit4] 2> 948904 T4397 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/conf
[junit4] 2> 948904 T4397 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 948905 T4397 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 948905 T4397 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 948905 T4397 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 948906 T4398 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1daa20c[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 948906 T4397 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 948907 T4362 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 948907 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 948914 T4362 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 948918 T4362 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50683
[junit4] 2> 948920 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 948920 T4362 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 948921 T4362 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater
[junit4] 2> 948921 T4362 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/'
[junit4] 2> 948935 T4362 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/solr.xml
[junit4] 2> 948957 T4362 oasc.CoreContainer.<init> New CoreContainer 32185183
[junit4] 2> 948957 T4362 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/]
[junit4] 2> 948958 T4362 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 948958 T4362 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 948959 T4362 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 948959 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 948959 T4362 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 948959 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 948959 T4362 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 948960 T4362 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 948960 T4362 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 948961 T4362 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 948961 T4362 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 948961 T4362 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 948963 T4409 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/'
[junit4] 2> 948986 T4409 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 949014 T4409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 949015 T4409 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 949018 T4409 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 949024 T4409 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 949025 T4409 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/
[junit4] 2> 949025 T4409 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 949025 T4409 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/
[junit4] 2> 949026 T4409 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 949026 T4409 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data
[junit4] 2> 949026 T4409 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index/
[junit4] 2> 949027 T4409 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 949027 T4409 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index
[junit4] 2> 949027 T4409 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=5.0244140625, floorSegmentMB=1.4697265625, forceMergeDeletesPctAllowed=14.336040805130917, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 949028 T4409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe),segFN=segments_1,generation=1}
[junit4] 2> 949028 T4409 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 949029 T4409 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 949029 T4409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 949029 T4409 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 949029 T4409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 949029 T4409 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 949030 T4409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 949030 T4409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 949032 T4409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 949033 T4409 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 949034 T4409 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 949034 T4409 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=22.0595703125, floorSegmentMB=1.041015625, forceMergeDeletesPctAllowed=7.125655597650392, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 949034 T4409 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe),segFN=segments_1,generation=1}
[junit4] 2> 949034 T4409 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 949035 T4409 oass.SolrIndexSearcher.<init> Opening Searcher@172579d[collection1] main
[junit4] 2> 949035 T4409 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/conf
[junit4] 2> 949035 T4409 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/conf/}
[junit4] 2> 949035 T4409 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/conf
[junit4] 2> 949036 T4409 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 949036 T4409 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 949036 T4409 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 949036 T4409 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 949037 T4409 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 949037 T4409 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 949037 T4409 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 949037 T4409 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 949037 T4410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@172579d[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 949038 T4362 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 949038 T4362 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C2642 name=collection1 org.apache.solr.core.SolrCore@17d229
[junit4] 2> 949040 T4370 C2642 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0]} 0 0
[junit4] 2> 949042 T4371 C2642 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1]} 0 0
[junit4] 2> 949044 T4368 C2642 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2]} 0 0
[junit4] 2> 949046 T4369 C2642 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 949048 T4369 C2642 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62018b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f2d15d),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62018b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f2d15d),segFN=segments_2,generation=2}
[junit4] 2> 949048 T4369 C2642 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 949050 T4369 C2642 oass.SolrIndexSearcher.<init> Opening Searcher@30e95d[collection1] main
[junit4] 2> 949050 T4369 C2642 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 949050 T4373 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30e95d[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):C3)}
[junit4] 2> 949050 T4369 C2642 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin&softCommit=false&waitSearcher=true&commit=true} {commit=} 0 4
[junit4] 2> 949053 T4412 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 949053 T4412 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 949054 T4370 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin} status=0 QTime=0
[junit4] 2> 949054 T4412 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 949055 T4412 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 949055 T4412 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 949058 T4371 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=filelist&wt=javabin&generation=2} status=0 QTime=2
[junit4] 2> 949058 T4412 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 949059 T4412 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index.20140529020515540
[junit4] 2> 949059 T4412 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 949059 T4412 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 949059 T4412 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 949060 T4412 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@1820a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bee5ff) fullCopy=false
[junit4] 2> 949062 T4368 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_nrm.cfe&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=1
[junit4] 2> 949064 T4369 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fdt&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949067 T4370 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fnm&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949069 T4371 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=segments_2&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949072 T4368 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.frq&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949075 T4369 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_nrm.cfs&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949077 T4370 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fdx&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949080 T4371 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.tim&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949082 T4368 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.si&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949085 T4369 C2642 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.tip&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949085 T4412 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 949087 T4412 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 949087 T4412 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.40234375, floorSegmentMB=0.6396484375, forceMergeDeletesPctAllowed=29.211025111490176, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 949088 T4412 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe),segFN=segments_2,generation=2}
[junit4] 2> 949088 T4412 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 949088 T4412 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 949089 T4412 oass.SolrIndexSearcher.<init> Opening Searcher@1ab3bff[collection1] main
[junit4] 2> 949089 T4410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ab3bff[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):C3)}
[junit4] 2> 949089 T4412 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index.20140529020515540 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index.20140529020515540;done=true>>]
[junit4] 2> 949090 T4412 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index.20140529020515540
[junit4] 2> ASYNC NEW_CORE C2643 name=collection1 org.apache.solr.core.SolrCore@1644720
[junit4] 2> 949090 T4405 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:49416/solr/replication&command=fetchindex&wait=true} status=0 QTime=38
[junit4] 2> 949093 T4406 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin&sort=id+desc} hits=3 status=0 QTime=1
[junit4] 2> 949094 T4415 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 949095 T4415 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 949095 T4407 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin} status=0 QTime=0
[junit4] 2> 949096 T4415 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 949096 T4415 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 949096 T4415 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 949098 T4408 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=filelist&wt=javabin&generation=2} status=0 QTime=0
[junit4] 2> 949099 T4415 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 949100 T4415 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index.20140529020515580
[junit4] 2> 949100 T4415 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 949100 T4415 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 949100 T4415 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 949100 T4415 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@1416faf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fa2efb) fullCopy=false
[junit4] 2> 949102 T4405 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_nrm.cfe&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949105 T4406 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fdt&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949107 T4407 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fnm&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949110 T4408 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=segments_2&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949113 T4405 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fdx&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=1
[junit4] 2> 949115 T4406 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_nrm.cfs&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949118 T4407 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.frq&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949120 T4408 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.tim&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949123 T4405 C2643 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.si&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2644 name=collection1 org.apache.solr.core.SolrCore@1644720
[junit4] 2> 949136 T4406 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.tip&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949136 T4415 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 949138 T4415 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 949138 T4415 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.40234375, floorSegmentMB=0.6396484375, forceMergeDeletesPctAllowed=29.211025111490176, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 949139 T4415 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ef7313 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65e491),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ef7313 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65e491),segFN=segments_2,generation=2}
[junit4] 2> 949140 T4415 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 949140 T4415 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 949141 T4415 oass.SolrIndexSearcher.<init> Opening Searcher@1159815[collection1] main
[junit4] 2> 949141 T4398 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1159815[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.9):C3)}
[junit4] 2> 949141 T4415 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index.20140529020515580 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index.20140529020515580;done=true>>]
[junit4] 2> 949141 T4415 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915053/slave/collection1/data/index.20140529020515580
[junit4] 2> ASYNC NEW_CORE C2645 name=collection1 org.apache.solr.core.SolrCore@22ab8a
[junit4] 2> 949142 T4393 C2645 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:50683/solr/replication&command=fetchindex&wait=true} status=0 QTime=48
[junit4] 2> 949144 T4394 C2645 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&version=2&wt=javabin&sort=id+desc} hits=3 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C2646 name=collection1 org.apache.solr.core.SolrCore@17d229
[junit4] 2> 949146 T4370 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=details&wt=javabin&_trace=getDetails} status=0 QTime=0
[junit4] 2> 949148 T4371 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=details&wt=javabin&_trace=getDetails} status=0 QTime=0
[junit4] 2> 949152 T4407 C2644 oash.ReplicationHandler.getReplicationDetails WARN Exception while invoking 'details' method for replication on master org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] 2> at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1657)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:658)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:263)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1962)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:769)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:579)
[junit4] 2> at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] 2> at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:178)
[junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:610)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:445)
[junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:452)
[junit4] 2> ... 35 more
[junit4] 2>
[junit4] 2> 949153 T4407 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=details&wt=javabin&_trace=getDetails} status=0 QTime=3
[junit4] 2> 949155 T4368 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin&_trace=assertVersions} status=0 QTime=0
[junit4] 2> 949157 T4408 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin&_trace=assertVersions} status=0 QTime=0
[junit4] 2> 949160 T4405 C2644 oash.ReplicationHandler.getReplicationDetails WARN Exception while invoking 'details' method for replication on master org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] 2> at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1657)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:658)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:263)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1962)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:769)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:579)
[junit4] 2> at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] 2> at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:178)
[junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:610)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:445)
[junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:452)
[junit4] 2> ... 35 more
[junit4] 2>
[junit4] 2> 949162 T4405 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=details&wt=javabin&_trace=getDetails} status=0 QTime=3
[junit4] 2> 949165 T4406 C2644 oash.ReplicationHandler.getReplicationDetails WARN Exception while invoking 'details' method for replication on master org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] 2> at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1657)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:658)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:263)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1962)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:769)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:579)
[junit4] 2> at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] 2> at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:178)
[junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:610)
[junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:445)
[junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:452)
[junit4] 2> ... 35 more
[junit4] 2>
[junit4] 2> 949166 T4406 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=details&wt=javabin&_trace=getDetails} status=0 QTime=2
[junit4] 2> 949171 T4407 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&slave=false&version=2&command=details&wt=javabin} status=0 QTime=1
[junit4] 2> 949171 T4395 C2645 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=details&wt=javabin&_trace=getDetails} status=0 QTime=3
[junit4] 2> 949173 T4408 C2644 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin&_trace=assertVersions} status=0 QTime=0
[junit4] 2> 949175 T4393 C2645 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin&_trace=assertVersions} status=0 QTime=0
[junit4] 2> 949178 T4405 C2644 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0]} 0 1
[junit4] 2> 949180 T4406 C2644 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1]} 0 0
[junit4] 2> 949182 T4407 C2644 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2]} 0 0
[junit4] 2> 949184 T4408 C2644 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[3]} 0 0
[junit4] 2> 949186 T4405 C2644 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 949189 T4405 C2644 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe),segFN=segments_3,generation=3}
[junit4] 2> 949190 T4405 C2644 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 949191 T4405 C2644 oass.SolrIndexSearcher.<init> Opening Searcher@b12272[collection1] main
[junit4] 2> 949191 T4405 C2644 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 949191 T4410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b12272[collection1] main{StandardDirectoryReader(segments_3:7:nrt _1(4.9):C4)}
[junit4] 2> 949192 T4405 C2644 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin&softCommit=false&waitSearcher=true&commit=true} {commit=} 0 6
[junit4] 2> 949193 T4417 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 949193 T4417 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 949195 T4369 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=indexversion&wt=javabin} status=0 QTime=0
[junit4] 2> 949195 T4417 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 949195 T4417 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 949195 T4417 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 949198 T4370 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&version=2&command=filelist&wt=javabin&generation=2} status=0 QTime=1
[junit4] 2> 949198 T4417 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 949199 T4417 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index.20140529020515679
[junit4] 2> 949199 T4417 oash.SnapPuller.fetchLatestIndex Starting download to MockDirectoryWrapper(RAMDirectory@77a0a4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19553ca) fullCopy=true
[junit4] 2> 949201 T4371 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_nrm.cfe&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949203 T4368 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fdt&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949206 T4369 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fnm&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949208 T4370 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=segments_2&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949211 T4371 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.frq&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949213 T4368 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_nrm.cfs&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949216 T4369 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.fdx&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949218 T4370 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.tim&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949221 T4371 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0.si&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949223 T4368 C2646 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&file=_0_Lucene40_0.tip&command=filecontent&wt=filestream&generation=2&checksum=true} status=0 QTime=0
[junit4] 2> 949224 T4417 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 949225 T4417 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20140529020515679
[junit4] 2> 949225 T4417 oash.SnapPuller.fetchLatestIndex removing old index directory MockDirectoryWrapper(RAMDirectory@89f96a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@150ddbe)
[junit4] 2> 949226 T4417 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 949226 T4417 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 949226 T4417 oasu.DefaultSolrCoreState.rollbackIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 949226 T4417 oasc.SolrCore.getNewIndexDir New index directory detected: old=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index/ new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312915389/repeater/collection1/data/index.20140529020515679
[junit4] 2> 949227 T4417 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.40234375, floorSegmentMB=0.6396484375, forceMergeDeletesPctAllowed=29.211025111490176, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 949227 T4417 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[...truncated too long message...]
esc} hits=492 status=0 QTime=1
[junit4] 2> 982584 T4362 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 982605 T4362 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26845270
[junit4] 2> 982606 T4362 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@364e65
[junit4] 2> 982606 T4362 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> 982607 T4362 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 982607 T4362 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 982607 T4362 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 982608 T4362 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 982608 T4362 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 982608 T4362 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946549/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946549/master/collection1/data;done=false>>]
[junit4] 2> 982609 T4362 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946549/master/collection1/data
[junit4] 2> 982609 T4362 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946549/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946549/master/collection1/data/index;done=false>>]
[junit4] 2> 982609 T4362 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946549/master/collection1/data/index
[junit4] 2> 982610 T4362 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 982681 T4362 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=12912692
[junit4] 2> 982682 T4362 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@fb5ac4
[junit4] 2> 982682 T4362 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> 982683 T4362 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 982683 T4362 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 982684 T4362 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 982684 T4362 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 982685 T4362 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 982685 T4362 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946663/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946663/slave/collection1/data/index;done=false>>]
[junit4] 2> 982685 T4362 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946663/slave/collection1/data/index
[junit4] 2> 982686 T4362 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946663/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946663/slave/collection1/data;done=false>>]
[junit4] 2> 982686 T4362 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401312946663/slave/collection1/data
[junit4] 2> 982686 T4362 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 982748 T4362 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.handler.TestReplicationHandler-F168D4CC528CAE9D-001
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=fr, timezone=Iran
[junit4] 2> NOTE: Linux 3.8.0-39-generic i386/Oracle Corporation 1.7.0_60-ea (32-bit)/cpus=8,threads=1,free=68011256,total=250028032
[junit4] 2> NOTE: All tests run in this JVM: [SignatureUpdateProcessorFactoryTest, TestAtomicUpdateErrorCases, HighlighterTest, ZkSolrClientTest, CollectionsAPIDistributedZkTest, PreAnalyzedFieldTest, StressHdfsTest, HdfsChaosMonkeySafeLeaderTest, QueryResultKeyTest, TestRandomMergePolicy, LeaderElectionIntegrationTest, TestQuerySenderNoQuery, SpellCheckCollatorTest, HdfsLockFactoryTest, DistributedExpandComponentTest, HardAutoCommitTest, TestSimpleQParserPlugin, TestCodecSupport, TestMultiCoreConfBootstrap, TermVectorComponentDistributedTest, CollectionsAPIAsyncDistributedZkTest, PathHierarchyTokenizerFactoryTest, TestManagedResource, TestConfigSets, TestRecoveryHdfs, StatsComponentTest, DebugComponentTest, StandardRequestHandlerTest, XmlUpdateRequestHandlerTest, FileUtilsTest, TriLevelCompositeIdRoutingTest, XsltUpdateRequestHandlerTest, TestSchemaResource, TestJmxIntegration, SolrRequestParserTest, SpellingQueryConverterTest, DefaultValueUpdateProcessorTest, TestBinaryField, IndexSchemaRuntimeFieldTest, UniqFieldsUpdateProcessorFactoryTest, TestSweetSpotSimilarityFactory, HighlighterConfigTest, HdfsWriteToMultipleCollectionsTest, ShardRoutingCustomTest, TestCoreDiscovery, DistanceFunctionTest, TestCloudManagedSchema, SuggesterWFSTTest, TestDynamicFieldResource, TestSchemaVersionResource, TestXIncludeConfig, HdfsCollectionsAPIDistributedZkTest, ChaosMonkeySafeLeaderTest, SortByFunctionTest, TestIBSimilarityFactory, DirectUpdateHandlerTest, HdfsSyncSliceTest, TestExpandComponent, OpenExchangeRatesOrgProviderTest, TestShardHandlerFactory, FieldAnalysisRequestHandlerTest, BasicZkTest, NumericFieldsTest, SolrPluginUtilsTest, TestCollationKeyRangeQueries, SimplePostToolTest, TestUpdate, TestManagedSchema, TestPhraseSuggestions, TestMaxScoreQueryParser, MergeStrategyTest, TestNRTOpen, TestQueryTypes, TestQuerySenderListener, TestNonDefinedSimilarityFactory, AlternateDirectoryTest, PingRequestHandlerTest, DateFieldTest, TestRangeQuery, TestShortCircuitedRequests, TestSearcherReuse, MultiThreadedOCPTest, TestManagedResourceStorage, HttpPartitionTest, CoreAdminCreateDiscoverTest, PrimUtilsTest, DirectSolrSpellCheckerTest, OpenCloseCoreStressTest, TestBinaryResponseWriter, CursorMarkTest, TestStressRecovery, TestFiltering, TestNumberUtils, TestCollationFieldDocValues, TestIndexingPerformance, OverseerTest, RecoveryZkTest, TestReplicationHandler]
[junit4] Completed on J1 in 34.32s, 14 tests, 1 error <<< FAILURES!
[...truncated 596 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1297: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:921: There were test failures: 396 suites, 1640 tests, 1 error, 36 ignored (15 assumptions)
Total time: 61 minutes 44 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_60-ea-b15 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure