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/29 05:54:21 UTC
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.8.0) - Build # 1568 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1568/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Could not remove the following files (in the order of attempts): /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/snapshot.rmmxxbeohllmy
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/snapshot.rmmxxbeohllmy
at __randomizedtesting.SeedInfo.seed([4663FFA1657C3442:7E8DFC442C2C70D]: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:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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 11213 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-4663FFA1657C3442-001/init-core-data-001
[junit4] 2> 2483478 T9315 oas.SolrTestCaseJ4.setUp ###Starting testNoWriter
[junit4] 2> 2483493 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2483498 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56935
[junit4] 2> 2483500 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2483501 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2483501 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master
[junit4] 2> 2483501 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/'
[junit4] 2> 2483549 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/solr.xml
[junit4] 2> 2483565 T9315 oasc.CoreContainer.<init> New CoreContainer 2005654738
[junit4] 2> 2483566 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/]
[junit4] 2> 2483566 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2483566 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2483567 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2483567 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2483567 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2483568 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2483569 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2483570 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2483570 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2483576 T9325 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/'
[junit4] 2> 2483602 T9325 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2483608 T9325 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2483609 T9325 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2483612 T9325 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2483619 T9325 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2483619 T9325 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/
[junit4] 2> 2483619 T9325 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2483620 T9325 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/
[junit4] 2> 2483620 T9325 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2483620 T9325 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2483620 T9325 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index/
[junit4] 2> 2483621 T9325 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2483621 T9325 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2483621 T9325 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2483622 T9325 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13a4d3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ccf1b4),segFN=segments_1,generation=1}
[junit4] 2> 2483622 T9325 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483624 T9325 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2483625 T9325 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2483626 T9325 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2483626 T9325 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2483632 T9325 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2483634 T9325 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2483634 T9325 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2483634 T9325 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2483636 T9325 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13a4d3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ccf1b4),segFN=segments_1,generation=1}
[junit4] 2> 2483636 T9325 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483636 T9325 oass.SolrIndexSearcher.<init> Opening Searcher@34380594[collection1] main
[junit4] 2> 2483637 T9325 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2483637 T9325 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf/}
[junit4] 2> 2483637 T9325 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2483638 T9325 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2483638 T9325 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2483638 T9325 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2483638 T9325 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2483639 T9325 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2483639 T9325 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2483639 T9326 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34380594[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2483640 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2483641 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2483659 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2483668 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56936
[junit4] 2> 2483668 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2483668 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2483669 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave
[junit4] 2> 2483674 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/'
[junit4] 2> 2483731 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/solr.xml
[junit4] 2> 2483743 T9315 oasc.CoreContainer.<init> New CoreContainer 1344105969
[junit4] 2> 2483744 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/]
[junit4] 2> 2483745 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2483746 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2483746 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2483746 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2483747 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2483748 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2483748 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2483749 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2483750 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2483750 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2483751 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2483752 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2483757 T9337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/'
[junit4] 2> 2483796 T9337 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2483804 T9337 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2483805 T9337 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2483808 T9337 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2483811 T9337 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2483812 T9337 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/
[junit4] 2> 2483812 T9337 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2483813 T9337 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/
[junit4] 2> 2483813 T9337 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2483813 T9337 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2483814 T9337 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index/
[junit4] 2> 2483814 T9337 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2483814 T9337 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2483815 T9337 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2483816 T9337 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16613950 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f4418e3),segFN=segments_1,generation=1}
[junit4] 2> 2483817 T9337 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483818 T9337 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2483818 T9337 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2483818 T9337 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2483819 T9337 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2483821 T9337 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2483822 T9337 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2483822 T9337 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2483822 T9337 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2483823 T9337 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16613950 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f4418e3),segFN=segments_1,generation=1}
[junit4] 2> 2483823 T9337 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2483823 T9337 oass.SolrIndexSearcher.<init> Opening Searcher@6c997ab5[collection1] main
[junit4] 2> 2483824 T9337 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2483824 T9337 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf/}
[junit4] 2> 2483824 T9337 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2483825 T9337 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2483825 T9337 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2483825 T9337 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2483832 T9337 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2483832 T9337 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2483832 T9338 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c997ab5[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2483833 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2483833 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2483848 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1344105969
[junit4] 2> 2483850 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@35b45dfa
[junit4] 2> 2483851 T9315 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> 2483852 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2483852 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2483853 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2483853 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2483854 T9315 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2483854 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data;done=false>>]
[junit4] 2> 2483855 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2483856 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index;done=false>>]
[junit4] 2> 2483856 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2483857 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2483925 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2005654738
[junit4] 2> 2483927 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@42b37b98
[junit4] 2> 2483927 T9315 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> 2483927 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2483928 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2483928 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2483929 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2483929 T9315 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2483930 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index;done=false>>]
[junit4] 2> 2483930 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2483931 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data;done=false>>]
[junit4] 2> 2483931 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2483932 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2483984 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2483989 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56936
[junit4] 2> 2483990 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2483990 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2483991 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave
[junit4] 2> 2483991 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/'
[junit4] 2> 2484011 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/solr.xml
[junit4] 2> 2484019 T9315 oasc.CoreContainer.<init> New CoreContainer 1846283972
[junit4] 2> 2484019 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/]
[junit4] 2> 2484021 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484021 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484021 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484022 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484023 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484023 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484024 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484024 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484024 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484027 T9350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/'
[junit4] 2> 2484054 T9350 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484070 T9350 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484073 T9350 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484076 T9350 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484079 T9350 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484080 T9350 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/
[junit4] 2> 2484080 T9350 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2484080 T9350 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/
[junit4] 2> 2484081 T9350 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484081 T9350 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484082 T9350 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index/
[junit4] 2> 2484082 T9350 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484083 T9350 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484083 T9350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=24.92578125, floorSegmentMB=1.541015625, forceMergeDeletesPctAllowed=23.864403221045638, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3630192332435702
[junit4] 2> 2484086 T9350 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2484087 T9350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484088 T9350 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484088 T9350 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484089 T9350 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484090 T9350 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484091 T9350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484092 T9350 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484092 T9350 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484094 T9350 oass.SolrIndexSearcher.<init> Opening Searcher@44eb603d[collection1] main
[junit4] 2> 2484095 T9350 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484095 T9350 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf/}
[junit4] 2> 2484096 T9350 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484096 T9350 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484096 T9350 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484097 T9350 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484098 T9350 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2484098 T9350 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484098 T9351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44eb603d[collection1] main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2484099 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484099 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2484100 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484106 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56935
[junit4] 2> 2484108 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484108 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484109 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master
[junit4] 2> 2484110 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/'
[junit4] 2> 2484129 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/solr.xml
[junit4] 2> 2484136 T9315 oasc.CoreContainer.<init> New CoreContainer 116872752
[junit4] 2> 2484137 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/]
[junit4] 2> 2484138 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484138 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484139 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484139 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484139 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484140 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484140 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484140 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484141 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484141 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484142 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484142 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484145 T9363 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/'
[junit4] 2> 2484170 T9363 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484177 T9363 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484179 T9363 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484182 T9363 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484183 T9363 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484185 T9363 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/
[junit4] 2> 2484185 T9363 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2484186 T9363 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/
[junit4] 2> 2484187 T9363 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484187 T9363 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2484188 T9363 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index/
[junit4] 2> 2484188 T9363 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484188 T9363 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2484189 T9363 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=24.92578125, floorSegmentMB=1.541015625, forceMergeDeletesPctAllowed=23.864403221045638, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3630192332435702
[junit4] 2> 2484192 T9363 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2484193 T9363 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484194 T9363 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484195 T9363 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484196 T9363 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484196 T9363 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484197 T9363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484198 T9363 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484199 T9363 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484200 T9363 oass.SolrIndexSearcher.<init> Opening Searcher@7f6624fd[collection1] main
[junit4] 2> 2484201 T9363 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2484202 T9363 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf/}
[junit4] 2> 2484202 T9363 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/conf
[junit4] 2> 2484203 T9363 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484203 T9363 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484203 T9363 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484204 T9363 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2484204 T9363 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2484204 T9363 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484204 T9364 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f6624fd[collection1] main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2484205 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484206 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1956 name=collection1 org.apache.solr.core.SolrCore@2285e29c
[junit4] 2> 2484211 T9344 C1956 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=24.92578125, floorSegmentMB=1.541015625, forceMergeDeletesPctAllowed=23.864403221045638, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3630192332435702
[junit4] 2> 2484213 T9344 C1956 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2484214 T9344 C1956 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484216 T9344 C1956 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123456]} 0 6
[junit4] 2> 2484219 T9345 C1956 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2484238 T9345 C1956 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index,segFN=segments_2,generation=2}
[junit4] 2> 2484238 T9345 C1956 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2484247 T9345 C1956 oass.SolrIndexSearcher.<init> Opening Searcher@5d7da349[collection1] main
[junit4] 2> 2484248 T9345 C1956 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2484248 T9351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d7da349[collection1] main{StandardDirectoryReader(segments_2:3 _0(4.9):C1)}
[junit4] 2> 2484249 T9345 C1956 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 30
[junit4] 2> 2484265 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1846283972
[junit4] 2> 2484266 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2285e29c
[junit4] 2> 2484267 T9315 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=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2484268 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2484268 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2484268 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2484270 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2484271 T9315 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2484271 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data;done=false>>]
[junit4] 2> 2484272 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484272 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index;done=false>>]
[junit4] 2> 2484272 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484273 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2484326 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484332 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56936
[junit4] 2> 2484336 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484336 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484337 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave
[junit4] 2> 2484337 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/'
[junit4] 2> 2484381 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/solr.xml
[junit4] 2> 2484395 T9315 oasc.CoreContainer.<init> New CoreContainer 632777705
[junit4] 2> 2484396 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/]
[junit4] 2> 2484396 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484396 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484397 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484398 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484398 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484399 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484399 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484400 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484401 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484405 T9375 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/'
[junit4] 2> 2484449 T9375 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484460 T9375 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484462 T9375 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484467 T9375 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484474 T9375 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484475 T9375 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/
[junit4] 2> 2484475 T9375 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2484476 T9375 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/
[junit4] 2> 2484476 T9375 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484477 T9375 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484477 T9375 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index/
[junit4] 2> 2484478 T9375 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484479 T9375 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484479 T9375 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484479 T9375 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484480 T9375 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484480 T9375 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484480 T9375 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484487 T9375 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484488 T9375 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484488 T9375 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484489 T9375 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484491 T9375 oass.SolrIndexSearcher.<init> Opening Searcher@168ccdc5[collection1] main
[junit4] 2> 2484491 T9375 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484491 T9375 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf/}
[junit4] 2> 2484492 T9375 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/conf
[junit4] 2> 2484492 T9375 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484492 T9375 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484493 T9375 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484494 T9375 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2484494 T9375 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484494 T9376 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@168ccdc5[collection1] main{StandardDirectoryReader(segments_2:3 _0(4.9):C1)}
[junit4] 2> 2484495 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484496 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2484504 T9379 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2484505 T9379 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> ASYNC NEW_CORE C1957 name=collection1 org.apache.solr.core.SolrCore@74c4684b
[junit4] 2> 2484508 T9358 C1957 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C1958 name=collection1 org.apache.solr.core.SolrCore@4dcff679
[junit4] 2> 2484509 T9370 C1958 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56935/solr/replication&command=fetchindex} status=0 QTime=7
[junit4] 2> 2484511 T9315 oas.SolrTestCaseJ4.tearDown ###Ending testNoWriter
[junit4] 2> 2484526 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=116872752
[junit4] 2> 2484528 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@74c4684b
[junit4] 2> 2484528 T9315 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> 2484528 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2484529 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2484529 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2484530 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2484531 T9315 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2484532 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index;done=false>>]
[junit4] 2> 2484532 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data/index
[junit4] 2> 2484533 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data;done=false>>]
[junit4] 2> 2484533 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409475/master/collection1/data
[junit4] 2> 2484534 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2484601 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=632777705
[junit4] 2> 2484603 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4dcff679
[junit4] 2> 2484604 T9315 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> 2484605 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2484605 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2484605 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2484606 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2484607 T9315 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2484607 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index;done=false>>]
[junit4] 2> 2484608 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data/index
[junit4] 2> 2484608 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data;done=false>>]
[junit4] 2> 2484608 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334409637/slave/collection1/data
[junit4] 2> 2484609 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2484695 T9315 oas.SolrTestCaseJ4.setUp ###Starting doTestBackup
[junit4] 2> 2484708 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484715 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56940
[junit4] 2> 2484716 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484717 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484717 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master
[junit4] 2> 2484718 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/'
[junit4] 2> 2484757 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/solr.xml
[junit4] 2> 2484781 T9315 oasc.CoreContainer.<init> New CoreContainer 841192868
[junit4] 2> 2484781 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/]
[junit4] 2> 2484782 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484782 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484783 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484784 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484784 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484784 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484785 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484785 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484786 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484789 T9390 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/'
[junit4] 2> 2484824 T9390 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484834 T9390 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484836 T9390 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484838 T9390 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484847 T9390 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484848 T9390 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/
[junit4] 2> 2484848 T9390 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2484849 T9390 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/
[junit4] 2> 2484849 T9390 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484850 T9390 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data
[junit4] 2> 2484850 T9390 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index/
[junit4] 2> 2484850 T9390 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484851 T9390 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index
[junit4] 2> 2484851 T9390 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2484857 T9390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cb4b1ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@274079ea),segFN=segments_1,generation=1}
[junit4] 2> 2484857 T9390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484858 T9390 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484858 T9390 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484858 T9390 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484859 T9390 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484863 T9390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2484867 T9390 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2484867 T9390 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2484868 T9390 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2484868 T9390 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cb4b1ac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@274079ea),segFN=segments_1,generation=1}
[junit4] 2> 2484869 T9390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484870 T9390 oass.SolrIndexSearcher.<init> Opening Searcher@5dba281f[collection1] main
[junit4] 2> 2484871 T9390 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/conf
[junit4] 2> 2484872 T9390 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/conf/}
[junit4] 2> 2484872 T9390 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/conf
[junit4] 2> 2484873 T9390 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2484874 T9390 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2484874 T9390 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2484874 T9390 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2484875 T9390 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2484875 T9390 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2484875 T9391 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5dba281f[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2484877 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2484878 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2484894 T9315 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2484903 T9315 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56941
[junit4] 2> 2484905 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2484905 T9315 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2484906 T9315 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave
[junit4] 2> 2484906 T9315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/'
[junit4] 2> 2484929 T9315 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/solr.xml
[junit4] 2> 2484937 T9315 oasc.CoreContainer.<init> New CoreContainer 845368923
[junit4] 2> 2484937 T9315 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/]
[junit4] 2> 2484938 T9315 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2484938 T9315 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2484938 T9315 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2484939 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2484939 T9315 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2484939 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2484940 T9315 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2484940 T9315 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2484940 T9315 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2484941 T9315 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2484941 T9315 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2484942 T9315 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2484946 T9402 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/'
[junit4] 2> 2484971 T9402 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
[junit4] 2> 2484979 T9402 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2484980 T9402 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2484983 T9402 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2484989 T9402 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2484990 T9402 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/
[junit4] 2> 2484990 T9402 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2484990 T9402 oasc.SolrCore.<init> [collection1] Opening new SolrCore at org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/
[junit4] 2> 2484991 T9402 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2484991 T9402 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data
[junit4] 2> 2484991 T9402 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/index/
[junit4] 2> 2484991 T9402 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2484992 T9402 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/data/index
[junit4] 2> 2484992 T9402 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2484992 T9402 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7db94452 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e02b103),segFN=segments_1,generation=1}
[junit4] 2> 2484992 T9402 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2484993 T9402 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2484996 T9402 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2484996 T9402 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2484997 T9402 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2484998 T9402 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2484998 T9402 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2484999 T9402 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2485001 T9402 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2485003 T9402 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2485003 T9402 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2485004 T9402 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=19.677734375, floorSegmentMB=0.677734375, forceMergeDeletesPctAllowed=12.683613162964528, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2485005 T9402 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7db94452 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e02b103),segFN=segments_1,generation=1}
[junit4] 2> 2485005 T9402 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2485006 T9402 oass.SolrIndexSearcher.<init> Opening Searcher@644f36c[collection1] main
[junit4] 2> 2485006 T9402 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/conf
[junit4] 2> 2485007 T9402 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/conf/}
[junit4] 2> 2485007 T9402 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410877/slave/collection1/conf
[junit4] 2> 2485008 T9402 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 2485008 T9402 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 2485008 T9402 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2485009 T9402 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2485009 T9402 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2485010 T9403 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@644f36c[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2485010 T9315 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2485011 T9315 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2485027 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=841192868
[junit4] 2> 2485028 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6b58966b
[junit4] 2> 2485028 T9315 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> 2485029 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2485029 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2485029 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2485030 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2485031 T9315 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2485032 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index;done=false>>]
[junit4] 2> 2485032 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data/index
[junit4] 2> 2485032 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data;done=false>>]
[junit4] 2> 2485033 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334410691/master/collection1/data
[...truncated too long message...]
]
[junit4] 2> 2528215 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451247/master/collection1/data
[junit4] 2> 2528215 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2528281 T9315 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1212786941
[junit4] 2> 2528282 T9315 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38797be8
[junit4] 2> 2528283 T9315 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> 2528285 T9315 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2528285 T9315 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2528285 T9315 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2528287 T9315 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2528287 T9315 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2528287 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data;done=false>>]
[junit4] 2> 2528288 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data
[junit4] 2> 2528289 T9315 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data/index;done=false>>]
[junit4] 2> 2528289 T9315 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1401334451418/slave/collection1/data/index
[junit4] 2> 2528290 T9315 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2528376 T9315 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.handler.TestReplicationHandler-4663FFA1657C3442-001
[junit4] 2> NOTE: test params are: codec=Lucene46: {name=PostingsFormat(name=FSTOrdPulsing41), id=PostingsFormat(name=FSTOrdPulsing41), newname=PostingsFormat(name=FSTOrdPulsing41)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ru, timezone=W-SU
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=64597440,total=416808960
[junit4] 2> NOTE: All tests run in this JVM: [MergeStrategyTest, ZkNodePropsTest, SolrCoreTest, TestSolrDeletionPolicy2, LegacyHTMLStripCharFilterTest, UpdateRequestProcessorFactoryTest, HdfsCollectionsAPIDistributedZkTest, SoftAutoCommitTest, BinaryUpdateRequestHandlerTest, TestCollationFieldDocValues, TermVectorComponentTest, PathHierarchyTokenizerFactoryTest, TestBinaryResponseWriter, RequestHandlersTest, RecoveryZkTest, MultiTermTest, DistributedSpellCheckComponentTest, TestSolrQueryParserDefaultOperatorResource, LeaderElectionIntegrationTest, TestLMDirichletSimilarityFactory, DistanceFunctionTest, TestSimpleQParserPlugin, DeleteReplicaTest, HighlighterConfigTest, TestExpandComponent, TestWordDelimiterFilterFactory, CollectionsAPIDistributedZkTest, TestManagedSchema, SimpleFacetsTest, TestBM25SimilarityFactory, TestHighlightDedupGrouping, TestDynamicFieldCollectionResource, ZkCLITest, CoreContainerCoreInitFailuresTest, DeleteInactiveReplicaTest, TestSolrJ, TestSolrXmlPersistence, CurrencyFieldOpenExchangeTest, TestAddFieldRealTimeGet, TestXIncludeConfig, TestArbitraryIndexDir, SearchHandlerTest, TestSchemaVersionResource, SolrIndexConfigTest, ResourceLoaderTest, TestJmxMonitoredMap, TestPostingsSolrHighlighter, TestFieldCollectionResource, ReturnFieldsTest, FieldMutatingUpdateProcessorTest, TestCoreContainer, OverseerStatusTest, DirectSolrSpellCheckerTest, OverseerRolesTest, IndexBasedSpellCheckerTest, TestCopyFieldCollectionResource, TestFieldTypeResource, TestRestManager, TestFreeTextSuggestions, HdfsRecoveryZkTest, MultiThreadedOCPTest, TestNonNRTOpen, DocumentAnalysisRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, NumericFieldsTest, OpenCloseCoreStressTest, DocValuesTest, ExternalFileFieldSortTest, TestReversedWildcardFilterFactory, FieldAnalysisRequestHandlerTest, TestNoOpRegenerator, CustomCollectionTest, DistributedQueryElevationComponentTest, TestHighFrequencyDictionaryFactory, TestRequestStatusCollectionAPI, TestSearcherReuse, TestComponentsName, ReplicationFactorTest, SignatureUpdateProcessorFactoryTest, FastVectorHighlighterTest, StatsComponentTest, NoCacheHeaderTest, TermsComponentTest, BlockCacheTest, TestStandardQParsers, BadCopyFieldTest, CircularListTest, HdfsBasicDistributedZk2Test, DateFieldTest, TestCSVLoader, DeleteShardTest, TestNRTOpen, TestCursorMarkWithoutUniqueKey, OverseerCollectionProcessorTest, CacheHeaderTest, PreAnalyzedFieldTest, DistributedExpandComponentTest, TestExtendedDismaxParser, TestInitQParser, DebugComponentTest, TestRTGBase, TestDefaultSimilarityFactory, TestMergePolicyConfig, SolrIndexSplitterTest, PrimitiveFieldTypeTest, QueryParsingTest, TestPartialUpdateDeduplication, TestOmitPositions, FileBasedSpellCheckerTest, TestPhraseSuggestions, TestSolrDeletionPolicy1, TestJoin, TestRandomFaceting, TestStressVersions, EchoParamsTest, TestLFUCache, DirectUpdateHandlerOptimizeTest, TestFieldSortValues, TestRandomDVFaceting, TestHashPartitioner, TestDocSet, TestStressLucene, ResponseHeaderTest, TestSearchPerf, PeerSyncTest, TestInfoStreamLogging, CoreMergeIndexesAdminHandlerTest, SortByFunctionTest, QueryResultKeyTest, RankQueryTest, DocumentBuilderTest, PrimUtilsTest, TestDFRSimilarityFactory, CoreAdminCreateDiscoverTest, TestRecovery, TestCloudManagedSchema, TestClassNameShortening, OverseerTest, ConnectionManagerTest, TestCharFilters, TestLuceneMatchVersion, TestCoreDiscovery, TestOverriddenPrefixQueryForCustomFieldType, SpellingQueryConverterTest, TestAnalyzeInfixSuggestions, DirectUpdateHandlerTest, TestFastWriter, HdfsDirectoryTest, TestAnalyzedSuggestions, LukeRequestHandlerTest, SolrXmlInZkTest, TestSystemIdResolver, SolrTestCaseJ4Test, QueryElevationComponentTest, CopyFieldTest, TestSchemaSimilarityResource, SolrRequestParserTest, TestFileDictionaryLookup, ClusterStateTest, TestExceedMaxTermLength, RAMDirectoryFactoryTest, TestSolrIndexConfig, TestRealTimeGet, ResponseLogComponentTest, BlockDirectoryTest, CollectionsAPIAsyncDistributedZkTest, SuggesterFSTTest, TestLRUCache, TestFastOutputStream, CurrencyFieldXmlFileTest, MigrateRouteKeyTest, TestMultiCoreConfBootstrap, SpellCheckComponentTest, TestSchemaResource, OutputWriterTest, SpatialFilterTest, HdfsUnloadDistributedZkTest, TestImplicitCoreProperties, DistributedQueryComponentCustomSortTest, RemoteQueryErrorTest, TestNumberUtils, SampleTest, TestConfigSets, TestMiniSolrCloudCluster, OpenExchangeRatesOrgProviderTest, HttpPartitionTest, ShardSplitTest, TestStressUserVersions, TestQueryTypes, DistributedSuggestComponentTest, NotRequiredUniqueKeyTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed in 44.93s, 14 tests, 1 error <<< FAILURES!
[...truncated 585 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1297: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:921: There were test failures: 396 suites, 1640 tests, 1 error, 41 ignored (14 assumptions)
Total time: 97 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure