You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/10/02 00:42:46 UTC
[JENKINS] Lucene-Solr-NightlyTests-4.x - Build # 392 - Still
Failing
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-4.x/392/
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Wed Oct 02 00:13:54 EET 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Wed Oct 02 00:13:54 EET 2013
at __randomizedtesting.SeedInfo.seed([52CE6495C721625A:89656453C2090BE9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1514)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:818)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:679)
Build Log:
[...truncated 10028 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 1278507 T3542 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 1278527 T3542 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1278531 T3542 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:64620
[junit4] 2> 1278532 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1278532 T3542 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1278533 T3542 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master
[junit4] 2> 1278533 T3542 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/'
[junit4] 2> 1278572 T3542 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/solr.xml
[junit4] 2> 1278646 T3542 oasc.CoreContainer.<init> New CoreContainer 862053657
[junit4] 2> 1278647 T3542 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/]
[junit4] 2> 1278649 T3542 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1278649 T3542 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1278650 T3542 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1278650 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1278650 T3542 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1278651 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1278651 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1278652 T3542 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1278652 T3542 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1278653 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1278660 T3542 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1278660 T3542 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1278666 T3552 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1
[junit4] 2> 1278667 T3552 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/'
[junit4] 2> 1278721 T3552 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1278783 T3552 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1278784 T3552 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1278790 T3552 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1278812 T3552 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1278814 T3552 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1278815 T3552 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/
[junit4] 2> 1278815 T3552 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1278815 T3552 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data
[junit4] 2> 1278816 T3552 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index/
[junit4] 2> 1278816 T3552 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1278816 T3552 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index
[junit4] 2> 1278817 T3552 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=89.6669921875, floorSegmentMB=0.23828125, forceMergeDeletesPctAllowed=22.984432852641, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3928579759457399
[junit4] 2> 1278818 T3552 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3a6c79f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@639b87d3),segFN=segments_1,generation=1}
[junit4] 2> 1278818 T3552 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1278819 T3552 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1278819 T3552 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1278820 T3552 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1278820 T3552 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1278820 T3552 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1278821 T3552 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1278821 T3552 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1278831 T3552 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1278834 T3552 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1278834 T3552 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1278835 T3552 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1278835 T3552 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3a6c79f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@639b87d3),segFN=segments_1,generation=1}
[junit4] 2> 1278836 T3552 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1278836 T3552 oass.SolrIndexSearcher.<init> Opening Searcher@3c0b1bb9 main
[junit4] 2> 1278836 T3552 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1278837 T3552 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1278837 T3552 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1278837 T3553 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c0b1bb9 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1278838 T3542 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0
[junit4] 2> 1278838 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1278839 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1278852 T3542 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1278854 T3542 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:64621
[junit4] 2> 1278855 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1278855 T3542 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1278856 T3542 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave
[junit4] 2> 1278856 T3542 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/'
[junit4] 2> 1278872 T3542 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/solr.xml
[junit4] 2> 1278929 T3542 oasc.CoreContainer.<init> New CoreContainer 471936885
[junit4] 2> 1278930 T3542 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/]
[junit4] 2> 1278932 T3542 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1278932 T3542 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1278933 T3542 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1278933 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1278934 T3542 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1278934 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1278935 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1278935 T3542 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1278935 T3542 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1278936 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1278937 T3542 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1278938 T3542 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1278943 T3564 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1
[junit4] 2> 1278944 T3564 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/'
[junit4] 2> 1278997 T3564 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1279052 T3564 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1279053 T3564 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1279059 T3564 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1279077 T3564 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1279079 T3564 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1279079 T3564 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/
[junit4] 2> 1279079 T3564 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1279080 T3564 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data
[junit4] 2> 1279080 T3564 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index/
[junit4] 2> 1279081 T3564 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1279081 T3564 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index
[junit4] 2> 1279082 T3564 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=89.6669921875, floorSegmentMB=0.23828125, forceMergeDeletesPctAllowed=22.984432852641, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3928579759457399
[junit4] 2> 1279083 T3564 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@5ef7ffc6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7e5d1d78),segFN=segments_1,generation=1}
[junit4] 2> 1279083 T3564 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1279084 T3564 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1279084 T3564 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1279084 T3564 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1279085 T3564 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1279085 T3564 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1279085 T3564 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1279086 T3564 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1279088 T3564 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1279091 T3564 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1279091 T3564 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1279092 T3564 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1279092 T3564 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@5ef7ffc6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7e5d1d78),segFN=segments_1,generation=1}
[junit4] 2> 1279093 T3564 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1279093 T3564 oass.SolrIndexSearcher.<init> Opening Searcher@512f4968 main
[junit4] 2> 1279099 T3564 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 1279099 T3565 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@512f4968 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1279099 T3564 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1279100 T3542 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0
[junit4] 2> 1279101 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1279101 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1279107 T3542 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=471936885
[junit4] 2> 1279108 T3542 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@52e0b1d7
[junit4] 2> 1279109 T3542 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> 1279109 T3542 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1279110 T3542 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1279110 T3542 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1279111 T3542 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1279112 T3542 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1279112 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data;done=false>>]
[junit4] 2> 1279113 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data
[junit4] 2> 1279113 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index;done=false>>]
[junit4] 2> 1279114 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index
[junit4] 2> 1279115 T3542 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1279168 T3542 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1279171 T3542 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:64622
[junit4] 2> 1279172 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1279172 T3542 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1279173 T3542 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave
[junit4] 2> 1279173 T3542 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/'
[junit4] 2> 1279188 T3542 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/solr.xml
[junit4] 2> 1279246 T3542 oasc.CoreContainer.<init> New CoreContainer 2052033697
[junit4] 2> 1279247 T3542 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/]
[junit4] 2> 1279249 T3542 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1279249 T3542 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1279250 T3542 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1279250 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1279250 T3542 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1279251 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1279251 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1279252 T3542 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1279252 T3542 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1279253 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1279254 T3542 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1279255 T3542 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1279267 T3577 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1
[junit4] 2> 1279267 T3577 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/'
[junit4] 2> 1279319 T3577 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1279374 T3577 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1279375 T3577 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1279381 T3577 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1279400 T3577 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1279401 T3577 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1279402 T3577 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/
[junit4] 2> 1279402 T3577 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1279402 T3577 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data
[junit4] 2> 1279403 T3577 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index/
[junit4] 2> 1279404 T3577 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1279405 T3577 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index
[junit4] 2> 1279406 T3577 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=24.30859375, floorSegmentMB=0.501953125, forceMergeDeletesPctAllowed=23.408295057384123, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8173405119911258
[junit4] 2> 1279410 T3577 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 1279411 T3577 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1279412 T3577 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1279412 T3577 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1279412 T3577 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1279413 T3577 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1279413 T3577 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1279413 T3577 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1279414 T3577 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1279416 T3577 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1279419 T3577 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1279419 T3577 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1279420 T3577 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=64.8994140625, floorSegmentMB=0.69921875, forceMergeDeletesPctAllowed=10.245653677101686, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1279421 T3577 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 1279422 T3577 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1279422 T3577 oass.SolrIndexSearcher.<init> Opening Searcher@43884e8d main
[junit4] 2> 1279423 T3577 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1279423 T3578 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@43884e8d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1279423 T3577 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1279424 T3542 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0
[junit4] 2> 1279424 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1279425 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1279430 T3542 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=862053657
[junit4] 2> 1279431 T3542 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@363022d5
[junit4] 2> 1279431 T3542 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> 1279432 T3542 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1279432 T3542 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1279433 T3542 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1279434 T3542 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1279434 T3542 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1279435 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index;done=false>>]
[junit4] 2> 1279435 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index
[junit4] 2> 1279436 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data;done=false>>]
[junit4] 2> 1279436 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data
[junit4] 2> 1279437 T3542 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1279491 T3542 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1279494 T3542 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:64623
[junit4] 2> 1279494 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1279495 T3542 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1279495 T3542 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master
[junit4] 2> 1279496 T3542 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/'
[junit4] 2> 1279511 T3542 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/solr.xml
[junit4] 2> 1279568 T3542 oasc.CoreContainer.<init> New CoreContainer 199290410
[junit4] 2> 1279569 T3542 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/]
[junit4] 2> 1279570 T3542 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1279571 T3542 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1279571 T3542 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1279572 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1279572 T3542 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1279573 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1279573 T3542 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1279574 T3542 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1279574 T3542 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1279575 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1279576 T3542 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1279576 T3542 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1279582 T3589 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1
[junit4] 2> 1279582 T3589 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/'
[junit4] 2> 1279636 T3589 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 1279693 T3589 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1279694 T3589 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1279700 T3589 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1279726 T3589 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1279728 T3589 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1279729 T3589 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/
[junit4] 2> 1279729 T3589 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1279729 T3589 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data
[junit4] 2> 1279730 T3589 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index/
[junit4] 2> 1279731 T3589 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1279732 T3589 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index
[junit4] 2> 1279733 T3589 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=24.30859375, floorSegmentMB=0.501953125, forceMergeDeletesPctAllowed=23.408295057384123, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8173405119911258
[junit4] 2> 1279738 T3589 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 1279738 T3589 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1279739 T3589 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1279739 T3589 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1279740 T3589 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1279740 T3589 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1279740 T3589 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1279740 T3589 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1279741 T3589 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1279743 T3589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1279746 T3589 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1279746 T3589 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1279747 T3589 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=64.8994140625, floorSegmentMB=0.69921875, forceMergeDeletesPctAllowed=10.245653677101686, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1279749 T3589 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 1279749 T3589 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1279749 T3589 oass.SolrIndexSearcher.<init> Opening Searcher@41b2d950 main
[junit4] 2> 1279750 T3589 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 1279750 T3589 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1279751 T3589 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1279751 T3590 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@41b2d950 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1279751 T3542 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0
[junit4] 2> 1279752 T3542 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1279752 T3542 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C980 name=collection1 org.apache.solr.core.SolrCore@4518f7fd
[junit4] 2> 1279762 T3582 C980 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1279763 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C981 name=collection1 org.apache.solr.core.SolrCore@7082cf56
[junit4] 2> 1279772 T3570 C981 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1279773 T3570 C981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2
[junit4] 2> 1279776 T3570 C981 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1279784 T3570 C981 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index,segFN=segments_2,generation=2}
[junit4] 2> 1279786 T3570 C981 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1279788 T3570 C981 oass.SolrIndexSearcher.<init> Opening Searcher@714fe7dd main
[junit4] 2> 1279789 T3570 C981 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1279789 T3578 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@714fe7dd main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 1279790 T3570 C981 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 2> 1279795 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 3
[junit4] 2> 1279798 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 1279801 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 1279804 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 1279807 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 1279810 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 1279814 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 1279816 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 1279829 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 10
[junit4] 2> 1279836 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 3
[junit4] 2> 1279840 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 1279843 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 1279846 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 1279849 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 1279852 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 1279855 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 1279858 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 1279869 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 8
[junit4] 2> 1279873 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 2
[junit4] 2> 1279876 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 1279879 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 1279882 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 1279886 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 1279888 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 1279891 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 1279894 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 1279906 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 9
[junit4] 2> 1279911 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 2
[junit4] 2> 1279915 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 1279917 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 1279920 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 1279923 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 1279926 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 1279929 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 1279933 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 1
[junit4] 2> 1279945 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 9
[junit4] 2> 1279951 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 3
[junit4] 2> 1279953 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 1279956 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 1279959 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 1279962 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 1279965 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 1279968 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 1279971 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 1279984 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 10
[junit4] 2> 1279990 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 3
[junit4] 2> 1279993 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 1279996 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 1279999 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 1280002 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 0
[junit4] 2> 1280005 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 0
[junit4] 2> 1280008 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 1280011 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 0
[junit4] 2> 1280022 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 8
[junit4] 2> 1280026 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 2
[junit4] 2> 1280029 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 0
[junit4] 2> 1280032 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 0
[junit4] 2> 1280035 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 0
[junit4] 2> 1280038 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 0
[junit4] 2> 1280042 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 1
[junit4] 2> 1280044 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[60]} 0 0
[junit4] 2> 1280047 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[61]} 0 0
[junit4] 2> 1280058 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[62]} 0 8
[junit4] 2> 1280063 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[63]} 0 2
[junit4] 2> 1280066 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[64]} 0 0
[junit4] 2> 1280070 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[65]} 0 1
[junit4] 2> 1280073 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[66]} 0 0
[junit4] 2> 1280076 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[67]} 0 0
[junit4] 2> 1280079 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[68]} 0 0
[junit4] 2> 1280082 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[69]} 0 0
[junit4] 2> 1280086 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[70]} 0 1
[junit4] 2> 1280096 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[71]} 0 8
[junit4] 2> 1280102 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[72]} 0 3
[junit4] 2> 1280106 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[73]} 0 1
[junit4] 2> 1280108 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[74]} 0 0
[junit4] 2> 1280111 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[75]} 0 0
[junit4] 2> 1280114 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[76]} 0 0
[junit4] 2> 1280117 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[77]} 0 0
[junit4] 2> 1280120 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[78]} 0 0
[junit4] 2> 1280123 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[79]} 0 0
[junit4] 2> 1280137 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[80]} 0 11
[junit4] 2> 1280143 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[81]} 0 3
[junit4] 2> 1280147 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[82]} 0 1
[junit4] 2> 1280150 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[83]} 0 0
[junit4] 2> 1280153 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[84]} 0 0
[junit4] 2> 1280156 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[85]} 0 0
[junit4] 2> 1280159 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[86]} 0 0
[junit4] 2> 1280162 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[87]} 0 0
[junit4] 2> 1280165 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[88]} 0 0
[junit4] 2> 1280180 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[89]} 0 12
[junit4] 2> 1280186 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[90]} 0 3
[junit4] 2> 1280189 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[91]} 0 0
[junit4] 2> 1280192 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[92]} 0 0
[junit4] 2> 1280195 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[93]} 0 0
[junit4] 2> 1280198 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[94]} 0 0
[junit4] 2> 1280201 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[95]} 0 0
[junit4] 2> 1280204 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[96]} 0 0
[junit4] 2> 1280207 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[97]} 0 0
[junit4] 2> 1280224 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[98]} 0 14
[junit4] 2> 1280232 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[99]} 0 5
[junit4] 2> 1280235 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 1
[junit4] 2> 1280239 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 1
[junit4] 2> 1280242 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 1
[junit4] 2> 1280244 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 1280247 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104]} 0 0
[junit4] 2> 1280250 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 0
[junit4] 2> 1280253 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4] 2> 1280269 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 13
[junit4] 2> 1280276 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 4
[junit4] 2> 1280279 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 0
[junit4] 2> 1280282 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4] 2> 1280285 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 0
[junit4] 2> 1280289 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 1
[junit4] 2> 1280293 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 0
[junit4] 2> 1280295 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 1280299 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 0
[junit4] 2> 1280315 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 14
[junit4] 2> 1280323 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 5
[junit4] 2> 1280327 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 1
[junit4] 2> 1280329 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 1280332 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4] 2> 1280335 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4] 2> 1280338 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 0
[junit4] 2> 1280341 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 1280344 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 1280362 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 15
[junit4] 2> 1280369 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 4
[junit4] 2> 1280372 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4] 2> 1280375 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4] 2> 1280378 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4] 2> 1280381 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 1280384 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4] 2> 1280387 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4] 2> 1280390 T3582 C980 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1280575 T3582 C980 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394393/master/collection1/data/index,segFN=segments_2,generation=2}
[junit4] 2> 1280576 T3582 C980 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1280589 T3582 C980 oass.SolrIndexSearcher.<init> Opening Searcher@209dfec2 main
[junit4] 2> 1280590 T3582 C980 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1280590 T3590 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@209dfec2 main{StandardDirectoryReader(segments_2:32:nrt _0(4.6):C9 _1(4.6):C9 _2(4.6):C9 _3(4.6):C9 _4(4.6):C9 _5(4.6):C9 _6(4.6):C9 _7(4.6):C9 _8(4.6):C9 _9(4.6):C9 _a(4.6):C9 _b(4.6):C9 _c(4.6):C9 _d(4.6):C9 _e(4.6):C7)}
[junit4] 2> 1280591 T3582 C980 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 201
[junit4] 2> 1280602 T3582 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=133 status=0 QTime=8
[junit4] 2> 1280609 T3570 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=status&core=collection1&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1280613 T3592 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1280614 T3592 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1280615 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1280616 T3592 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 1280616 T3592 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 1280616 T3592 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 1280706 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=87
[junit4] 2> 1280708 T3592 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 121
[junit4] 2> 1280710 T3592 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index.20131002000956593
[junit4] 2> 1280816 T3592 oash.SnapPuller.fetchLatestIndex Starting download to org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380665394725/slave/collection1/data/index.20131002000956593 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7449f5f6 fullCopy=true
[junit4] 2> 1280821 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_9.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280827 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_8.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280833 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280839 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_d.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280845 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_a.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280851 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_e.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280857 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280863 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_9.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280869 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280875 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280881 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280887 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_8.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280893 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280899 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280905 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_4.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280911 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280917 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280923 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280929 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280935 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_d.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280941 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_a.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280947 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_d.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280953 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280959 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_8.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280967 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_9.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280974 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280981 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280988 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1280995 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_9.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281002 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281009 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_7.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281016 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281023 T3583 C980 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C982 name=collection1 org.apache.solr.core.SolrCore@4518f7fd
[junit4] 2> 1281037 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_e.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281044 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_8.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281051 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281058 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281066 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281073 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_6.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281080 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281087 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_a.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281094 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281101 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281108 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_6.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281115 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281122 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_e.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281128 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_6.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281135 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_b.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281142 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_e.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281149 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281157 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_e.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281165 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281173 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_9.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281181 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_6.tii&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281190 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_4.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281199 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_5.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281208 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281218 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_d.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281227 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_b.frq&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281234 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_8.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281242 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_3.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281250 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_d.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281258 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281266 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_d.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281274 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_6.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281283 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_6.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281292 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281300 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_a.nrm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281308 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.tis&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281315 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_5.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281323 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_4.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281330 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281338 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281345 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_4.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281352 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_c.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 1281359 T3583 C982 oasc.SolrCore.execute [collection1] webapp=/solr path=
[...truncated too long message...]
e.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2743493 T4298 C2863 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 2743494 T3542 oas.SolrTestCaseJ4.tearDown ###Ending doTestSnapPullWithMasterUrl
[junit4] 2> 2743495 T3542 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=47557992
[junit4] 2> 2743496 T3542 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@59a75898
[junit4] 2> 2743496 T3542 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=496,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 2743497 T3542 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2743498 T3542 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2743498 T3542 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2743499 T3542 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2743500 T3542 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2743501 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666855665/master/collection1/data/index.20131002003419021 [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666855665/master/collection1/data/index.20131002003419021;done=false>>]
[junit4] 2> 2743501 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666855665/master/collection1/data/index.20131002003419021
[junit4] 2> 2743525 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666855665/master/collection1/data [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666855665/master/collection1/data;done=false>>]
[junit4] 2> 2743526 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666855665/master/collection1/data
[junit4] 2> 2743527 T3542 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2743579 T3542 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1506883051
[junit4] 2> 2743581 T3542 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e86b54b
[junit4] 2> 2743581 T3542 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 2743583 T3542 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2743583 T3542 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2743584 T3542 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2743585 T3542 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2743586 T3542 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2743587 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666856042/slave/collection1/data [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666856042/slave/collection1/data;done=false>>]
[junit4] 2> 2743587 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666856042/slave/collection1/data
[junit4] 2> 2743588 T3542 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666856042/slave/collection1/data/index.20131002003418312 [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666856042/slave/collection1/data/index.20131002003418312;done=false>>]
[junit4] 2> 2743588 T3542 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1380666856042/slave/collection1/data/index.20131002003418312
[junit4] 2> 2743612 T3542 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2743680 T3542 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=ga_IE, timezone=Asia/Gaza
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=1,free=135922720,total=377815040
[junit4] 2> NOTE: All tests run in this JVM: [CacheHeaderTest, WordBreakSolrSpellCheckerTest, IndexSchemaTest, TermsComponentTest, RecoveryZkTest, TestStressVersions, RegexBoostProcessorTest, TestGroupingSearch, EchoParamsTest, StatelessScriptUpdateProcessorFactoryTest, CoreAdminHandlerTest, JsonLoaderTest, TestValueSourceCache, TestSchemaResource, TestDocSet, BasicFunctionalityTest, TestTrie, TestRecoveryHdfs, TestLFUCache, TestUniqueKeyFieldResource, LegacyHTMLStripCharFilterTest, HdfsLockFactoryTest, SliceStateTest, TestShardHandlerFactory, OverseerTest, CoreMergeIndexesAdminHandlerTest, CSVRequestHandlerTest, HdfsUnloadDistributedZkTest, TestLMJelinekMercerSimilarityFactory, ShowFileRequestHandlerTest, HdfsBasicDistributedZkTest, SuggesterTest, TestRemoteStreaming, CoreAdminCreateDiscoverTest, SampleTest, SyncSliceTest, TestAnalyzeInfixSuggestions, TestConfig, DocValuesMissingTest, TestBM25SimilarityFactory, SchemaVersionSpecificBehaviorTest, TestMergePolicyConfig, DocValuesTest, LukeRequestHandlerTest, TestNRTOpen, ReturnFieldsTest, SolrCoreTest, TestPostingsSolrHighlighter, TestRealTimeGet, ExternalFileFieldSortTest, SolrIndexSplitterTest, PeerSyncTest, CachingDirectoryFactoryTest, OverseerCollectionProcessorTest, FieldMutatingUpdateProcessorTest, MoreLikeThisHandlerTest, TestCharFilters, UpdateRequestProcessorFactoryTest, ResourceLoaderTest, SearchHandlerTest, TestSolrDeletionPolicy1, TestBadConfig, ParsingFieldUpdateProcessorsTest, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, ScriptEngineTest, TestJoin, BasicDistributedZkTest, TestSolrQueryParser, MBeansHandlerTest, QueryResultKeyTest, TestCloudManagedSchemaAddField, DocumentBuilderTest, AddSchemaFieldsUpdateProcessorFactoryTest, DistributedQueryElevationComponentTest, ZkCLITest, TestSearchPerf, DistributedTermsComponentTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestReplicationHandler]
[junit4] Completed on J0 in 1465.21s, 14 tests, 1 failure <<< FAILURES!
[...truncated 257 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.xml:434: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.xml:407: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build.xml:189: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/common-build.xml:455: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/common-build.xml:1233: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/common-build.xml:876: There were test failures: 327 suites, 1454 tests, 1 failure, 32 ignored (4 assumptions)
Total time: 182 minutes 47 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-NightlyTests-4.x - Build # 393 - Still
Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-4.x/393/
All tests passed
Build Log:
[...truncated 4078 lines...]
[javac] Compiling 28 source files to /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/build/suggest/classes/test
[javac] /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/suggest/src/test/org/apache/lucene/search/suggest/analyzing/TestFreeTextSuggester.java:576: cannot find symbol
[javac] symbol : class SafeVarargs
[javac] location: class org.apache.lucene.search.suggest.analyzing.TestFreeTextSuggester
[javac] @SafeVarargs
[javac] ^
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] 1 error
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.xml:434: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.xml:407: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/build.xml:552: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/common-build.xml:1889: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/module-build.xml:57: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/common-build.xml:723: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/common-build.xml:737: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene/common-build.xml:1627: Compile failed; see the compiler error output for details.
Total time: 42 minutes 32 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure