You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/27 13:22:13 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b102) - Build #
7102 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7102/
Java: 64bit/jdk1.8.0-ea-b102 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Tue Aug 27 11:10:36 GMT 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Tue Aug 27 11:10:36 GMT 2013
at __randomizedtesting.SeedInfo.seed([51EC3240223272A0:8A473286271A1B13]: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:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9901 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 335449 T649 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 335453 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 335476 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48113
[junit4] 2> 335477 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 335477 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 335477 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master
[junit4] 2> 335478 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/'
[junit4] 2> 335491 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/solr.xml
[junit4] 2> 335499 T649 oasc.CoreContainer.<init> New CoreContainer 314908180
[junit4] 2> 335499 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/]
[junit4] 2> 335499 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 335499 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 335500 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 335500 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 335500 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 335501 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 335501 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 335501 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 335502 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 335502 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 335508 T659 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1
[junit4] 2> 335508 T659 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/'
[junit4] 2> 335524 T659 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 335537 T659 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 335538 T659 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 335541 T659 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 335544 T659 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 335550 T659 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 335550 T659 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/
[junit4] 2> 335550 T659 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 335551 T659 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data
[junit4] 2> 335551 T659 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index/
[junit4] 2> 335551 T659 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 335552 T659 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index
[junit4] 2> 335552 T659 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 335553 T659 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@65640d3c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@79b560c8),segFN=segments_1,generation=1}
[junit4] 2> 335553 T659 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335554 T659 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 335554 T659 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 335555 T659 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 335555 T659 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 335555 T659 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 335555 T659 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 335555 T659 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 335558 T659 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 335559 T659 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 335559 T659 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 335559 T659 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 335559 T659 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@65640d3c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@79b560c8),segFN=segments_1,generation=1}
[junit4] 2> 335560 T659 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335560 T659 oass.SolrIndexSearcher.<init> Opening Searcher@dba7cb7 main
[junit4] 2> 335560 T659 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 335560 T659 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 335560 T660 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dba7cb7 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 335560 T659 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 335561 T649 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 335561 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 335562 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 335569 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 335571 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45822
[junit4] 2> 335571 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 335572 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 335572 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave
[junit4] 2> 335573 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/'
[junit4] 2> 335584 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/solr.xml
[junit4] 2> 335592 T649 oasc.CoreContainer.<init> New CoreContainer 778106390
[junit4] 2> 335593 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/]
[junit4] 2> 335593 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 335593 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 335594 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 335594 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 335594 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 335595 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 335595 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 335595 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 335595 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 335596 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 335600 T671 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1
[junit4] 2> 335600 T671 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/'
[junit4] 2> 335623 T671 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 335628 T671 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 335629 T671 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 335631 T671 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 335633 T671 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 335633 T671 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 335633 T671 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/
[junit4] 2> 335633 T671 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 335634 T671 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data
[junit4] 2> 335634 T671 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index/
[junit4] 2> 335634 T671 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 335634 T671 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index
[junit4] 2> 335635 T671 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 335635 T671 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f31fd29 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@534cd00d),segFN=segments_1,generation=1}
[junit4] 2> 335635 T671 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335636 T671 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 335636 T671 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 335636 T671 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 335636 T671 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 335637 T671 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 335637 T671 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 335637 T671 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 335638 T671 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 335638 T671 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 335638 T671 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 335639 T671 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 335639 T671 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f31fd29 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@534cd00d),segFN=segments_1,generation=1}
[junit4] 2> 335639 T671 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335639 T671 oass.SolrIndexSearcher.<init> Opening Searcher@5c371911 main
[junit4] 2> 335641 T671 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 335641 T672 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c371911 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 335641 T671 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 335642 T649 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 335642 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 335642 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 335667 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=778106390
[junit4] 2> 335668 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5080fbb
[junit4] 2> 335669 T649 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> 335669 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 335669 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 335670 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 335671 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 335671 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 335672 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index;done=false>>]
[junit4] 2> 335672 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index
[junit4] 2> 335672 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data;done=false>>]
[junit4] 2> 335673 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data
[junit4] 2> 335673 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 335748 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=314908180
[junit4] 2> 335749 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@10737de1
[junit4] 2> 335749 T649 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> 335749 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 335750 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 335750 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 335751 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 335751 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 335751 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data;done=false>>]
[junit4] 2> 335752 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data
[junit4] 2> 335752 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index;done=false>>]
[junit4] 2> 335752 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index
[junit4] 2> 335753 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 335806 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 335808 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57904
[junit4] 2> 335808 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 335809 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 335809 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master
[junit4] 2> 335809 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/'
[junit4] 2> 335821 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/solr.xml
[junit4] 2> 335836 T649 oasc.CoreContainer.<init> New CoreContainer 629053900
[junit4] 2> 335836 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/]
[junit4] 2> 335837 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 335837 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 335837 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 335838 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 335838 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 335838 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 335838 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 335839 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 335839 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 335839 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 335844 T684 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1
[junit4] 2> 335844 T684 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/'
[junit4] 2> 335857 T684 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 335869 T684 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 335870 T684 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 335871 T684 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 335873 T684 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 335874 T684 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 335874 T684 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/
[junit4] 2> 335874 T684 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 335874 T684 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data
[junit4] 2> 335874 T684 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index/
[junit4] 2> 335875 T684 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 335875 T684 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index
[junit4] 2> 335875 T684 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 335876 T684 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_1,generation=1}
[junit4] 2> 335876 T684 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335877 T684 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 335877 T684 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 335877 T684 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 335877 T684 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 335877 T684 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 335877 T684 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 335877 T684 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 335878 T684 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 335879 T684 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 335879 T684 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 335879 T684 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 335880 T684 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_1,generation=1}
[junit4] 2> 335880 T684 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335880 T684 oass.SolrIndexSearcher.<init> Opening Searcher@19743eaf main
[junit4] 2> 335880 T684 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 335880 T684 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 335880 T685 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19743eaf main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 335880 T684 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 335881 T649 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 335881 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 335882 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C572 name=collection1 org.apache.solr.core.SolrCore@44e0f1b4
[junit4] 2> 335887 T680 C572 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 335888 T680 C572 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 335890 T681 C572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 335891 T681 C572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_2,generation=2}
[junit4] 2> 335891 T681 C572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 335892 T681 C572 oass.SolrIndexSearcher.<init> Opening Searcher@46607806 main
[junit4] 2> 335892 T681 C572 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 335892 T685 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@46607806 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 335893 T681 C572 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4] 2> 335896 T682 C572 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 335898 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 335900 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45393
[junit4] 2> 335902 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 335902 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 335902 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave
[junit4] 2> 335903 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/'
[junit4] 2> 335913 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/solr.xml
[junit4] 2> 335919 T649 oasc.CoreContainer.<init> New CoreContainer 726127251
[junit4] 2> 335920 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/]
[junit4] 2> 335920 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 335920 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 335921 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 335921 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 335921 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 335921 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 335921 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 335922 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 335922 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 335922 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 335925 T696 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1
[junit4] 2> 335925 T696 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/'
[junit4] 2> 335950 T696 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 335956 T696 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 335957 T696 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 335959 T696 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 335962 T696 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 335962 T696 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 335963 T696 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/
[junit4] 2> 335963 T696 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 335965 T696 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data
[junit4] 2> 335965 T696 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index/
[junit4] 2> 335965 T696 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 335966 T696 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index
[junit4] 2> 335966 T696 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 335967 T696 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d0d6e0e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d7767d),segFN=segments_1,generation=1}
[junit4] 2> 335967 T696 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335968 T696 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 335968 T696 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 335968 T696 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 335968 T696 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 335968 T696 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 335969 T696 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 335969 T696 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 335970 T696 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 335971 T696 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 335971 T696 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 335972 T696 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 335972 T696 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d0d6e0e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d7767d),segFN=segments_1,generation=1}
[junit4] 2> 335972 T696 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 335973 T696 oass.SolrIndexSearcher.<init> Opening Searcher@776454a4 main
[junit4] 2> 335973 T696 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 335973 T697 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@776454a4 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 335973 T696 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 335974 T649 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 335974 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 335974 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C573 name=collection1 org.apache.solr.core.SolrCore@713d8657
[junit4] 2> 335979 T692 C573 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C574 name=collection1 org.apache.solr.core.SolrCore@44e0f1b4
[junit4] 2> 335982 T683 C574 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=1
[junit4] 2> 335984 T680 C574 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 335987 T681 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1
[junit4] 2> 335987 T681 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/'
[junit4] 2> 336009 T681 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 336015 T681 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 336016 T681 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 336018 T681 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 336020 T681 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 336020 T681 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/
[junit4] 2> 336021 T681 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 336021 T681 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index/
[junit4] 2> 336021 T681 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 336022 T681 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 336022 T681 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 336022 T681 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 336022 T681 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 336022 T681 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 336023 T681 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 336024 T681 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 336025 T681 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 336025 T681 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 336025 T681 oass.SolrIndexSearcher.<init> Opening Searcher@3374a4ea main
[junit4] 2> 336025 T681 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 336026 T681 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 336026 T681 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 336026 T700 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3374a4ea main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 336026 T681 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 336026 T681 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 336027 T681 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=95.7021484375, floorSegmentMB=1.9208984375, forceMergeDeletesPctAllowed=29.585316368231656, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7310824623975372
[junit4] 2> 336028 T681 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_2,generation=2}
[junit4] 2> 336028 T681 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 336028 T681 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 336028 T681 oass.SolrIndexSearcher.<init> Opening Searcher@6be39700 main
[junit4] 2> 336029 T700 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6be39700 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 336029 T681 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 336029 T681 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44e0f1b4
[junit4] 2> 336029 T681 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 336030 T681 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 336030 T681 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=44
[junit4] 2> ASYNC NEW_CORE C575 name=collection1 org.apache.solr.core.SolrCore@69536197
[junit4] 2> 336033 T682 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 336035 T683 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 336038 T680 C575 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 336041 T681 C575 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 336043 T682 C575 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 336046 T682 C575 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@633eece6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46dc63f0),segFN=segments_3,generation=3}
[junit4] 2> 336047 T682 C575 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 336048 T682 C575 oass.SolrIndexSearcher.<init> Opening Searcher@799eefd9 main
[junit4] 2> 336049 T682 C575 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 336049 T700 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@799eefd9 main{StandardDirectoryReader(segments_3:4:nrt _0(4.5):c2)}
[junit4] 2> 336049 T682 C575 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 2> 336053 T683 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=2
[junit4] 2> ASYNC NEW_CORE C576 name=collection1 org.apache.solr.core.SolrCore@713d8657
[junit4] 2> 336056 T693 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 336056 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336159 T694 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 336160 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336262 T695 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 336263 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336365 T692 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 336365 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336468 T694 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 336468 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336571 T695 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 336571 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336673 T693 C576 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 336674 T649 oash.TestReplicationHandler.rQuery Waiting for 2 docs
[junit4] 2> 336679 T683 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 336679 T698 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 336679 T698 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 336679 T698 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 336682 T680 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1
[junit4] 2> 336683 T698 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 336684 T698 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index.20130827111033006
[junit4] 2> 336684 T698 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@4cfe1eb2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38bf1334) fullCopy=false
[junit4] 2> 336687 T681 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1
[junit4] 2> 336690 T682 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 336693 T683 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 336695 T680 C575 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 336696 T698 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 336706 T698 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 336706 T698 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 336706 T698 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 336708 T698 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 336708 T698 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=41.841796875, floorSegmentMB=1.1484375, forceMergeDeletesPctAllowed=23.66559233990764, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3663136988479516
[junit4] 2> 336713 T698 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d0d6e0e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d7767d),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7d0d6e0e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d7767d),segFN=segments_3,generation=3}
[junit4] 2> 336713 T698 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 336714 T698 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 336715 T698 oass.SolrIndexSearcher.<init> Opening Searcher@71749428 main
[junit4] 2> 336715 T697 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71749428 main{StandardDirectoryReader(segments_3:4:nrt _0(4.5):c2)}
[junit4] 2> 336715 T698 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index.20130827111033006 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index.20130827111033006;done=true>>]
[junit4] 2> 336716 T698 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index.20130827111033006
[junit4] 2> ASYNC NEW_CORE C577 name=collection1 org.apache.solr.core.SolrCore@713d8657
[junit4] 2> 336776 T694 C577 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 336777 T649 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 336799 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=629053900
[junit4] 2> 336800 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@69536197
[junit4] 2> 336800 T649 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 336801 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 336801 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 336802 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 336803 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 336803 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 336803 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index;done=false>>]
[junit4] 2> 336804 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data/index
[junit4] 2> 336804 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data;done=false>>]
[junit4] 2> 336804 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831772/master/collection1/data
[junit4] 2> 336805 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 336879 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=726127251
[junit4] 2> 336879 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@713d8657
[junit4] 2> 336880 T649 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> 336880 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 336881 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 336881 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 336882 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 336882 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 336883 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data;done=false>>]
[junit4] 2> 336883 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data
[junit4] 2> 336883 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index;done=false>>]
[junit4] 2> 336884 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601831888/slave/collection1/data/index
[junit4] 2> 336884 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 336946 T649 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 336956 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 336959 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56900
[junit4] 2> 336960 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 336960 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 336961 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master
[junit4] 2> 336961 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/'
[junit4] 2> 336976 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/solr.xml
[junit4] 2> 336987 T649 oasc.CoreContainer.<init> New CoreContainer 628287477
[junit4] 2> 336987 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/]
[junit4] 2> 336988 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 336988 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 336989 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 336989 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 336989 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 336989 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 336990 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 336990 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 336990 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 336991 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 336997 T711 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1
[junit4] 2> 336997 T711 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/'
[junit4] 2> 337016 T711 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 337032 T711 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 337033 T711 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 337035 T711 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 337038 T711 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 337039 T711 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 337039 T711 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/data/
[junit4] 2> 337039 T711 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 337040 T711 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/data
[junit4] 2> 337040 T711 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/data/index/
[junit4] 2> 337040 T711 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 337040 T711 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833270/master/collection1/data/index
[junit4] 2> 337041 T711 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 337041 T711 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7e60e8b5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@329eab1a),segFN=segments_1,generation=1}
[junit4] 2> 337042 T711 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 337042 T711 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 337043 T711 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 337043 T711 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 337043 T711 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 337043 T711 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 337043 T711 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 337044 T711 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 337046 T711 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 337047 T711 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 337047 T711 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 337047 T711 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 337048 T711 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7e60e8b5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@329eab1a),segFN=segments_1,generation=1}
[junit4] 2> 337048 T711 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 337048 T711 oass.SolrIndexSearcher.<init> Opening Searcher@7a91fa6a main
[junit4] 2> 337049 T711 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 337049 T711 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 337049 T712 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a91fa6a main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 337049 T711 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 337050 T649 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 337050 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 337051 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 337058 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 337060 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40532
[junit4] 2> 337061 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 337061 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 337062 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave
[junit4] 2> 337062 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/'
[junit4] 2> 337075 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/solr.xml
[junit4] 2> 337084 T649 oasc.CoreContainer.<init> New CoreContainer 1872376058
[junit4] 2> 337084 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/]
[junit4] 2> 337085 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 337085 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 337086 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 337086 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 337086 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 337087 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 337087 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 337087 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 337088 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 337088 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 337093 T723 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1
[junit4] 2> 337093 T723 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/'
[junit4] 2> 337123 T723 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 337130 T723 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 337130 T723 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 337133 T723 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 337135 T723 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 337136 T723 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 337136 T723 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/
[junit4] 2> 337136 T723 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 337137 T723 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data
[junit4] 2> 337137 T723 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/index/
[junit4] 2> 337137 T723 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 337138 T723 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/index
[junit4] 2> 337138 T723 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 337138 T723 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4bab18b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62cba6e8),segFN=segments_1,generation=1}
[junit4] 2> 337139 T723 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 337139 T723 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 337140 T723 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 337140 T723 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 337140 T723 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 337140 T723 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 337140 T723 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 337141 T723 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 337142 T723 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 337143 T723 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 337143 T723 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 337143 T723 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 337144 T723 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4bab18b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62cba6e8),segFN=segments_1,generation=1}
[junit4] 2> 337144 T723 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 337144 T723 oass.SolrIndexSearcher.<init> Opening Searcher@3cd9dba6 main
[junit4] 2> 337144 T723 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 337145 T724 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cd9dba6 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 337145 T723 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 337145 T649 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 337146 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 337146 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 337174 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1872376058
[junit4] 2> 337175 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8082228
[junit4] 2> 337176 T649 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> 337176 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 337176 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 337177 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 337178 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 337178 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 337178 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data;done=false>>]
[junit4] 2> 337179 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data
[junit4] 2> 337179 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/index;done=false>>]
[junit4] 2> 337179 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/collection1/data/index
[junit4] 2> 337180 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 337232 T649 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 337234 T649 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:35683
[junit4] 2> 337235 T649 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 337235 T649 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 337235 T649 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave
[junit4] 2> 337236 T649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/'
[junit4] 2> 337245 T649 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/solr.xml
[junit4] 2> 337263 T649 oasc.CoreContainer.<init> New CoreContainer 1711650381
[junit4] 2> 337264 T649 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601833377/slave/]
[junit4] 2> 337264 T649 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 337264 T649 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 337265 T649 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 337265 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 337265 T649 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 337266 T649 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 337266 T649 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 337266 T649 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 337267 T649 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 337267 T649 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTime
[...truncated too long message...]
er closing IndexWriter with IndexWriterCloser
[junit4] 2> 395616 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 395617 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 395617 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891607/repeater/collection1/data/index.20130827111131780 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891607/repeater/collection1/data/index.20130827111131780;done=false>>]
[junit4] 2> 395617 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891607/repeater/collection1/data/index.20130827111131780
[junit4] 2> 395617 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891607/repeater/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891607/repeater/collection1/data;done=false>>]
[junit4] 2> 395618 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891607/repeater/collection1/data
[junit4] 2> 395618 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 395671 T649 oas.SolrTestCaseJ4.tearDown ###Ending doTestRepeater
[junit4] 2> 395691 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=482308956
[junit4] 2> 395692 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@541f413d
[junit4] 2> 395692 T649 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=6,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 395692 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 395692 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 395693 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 395693 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 395693 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 395694 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891301/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891301/master/collection1/data;done=false>>]
[junit4] 2> 395694 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891301/master/collection1/data
[junit4] 2> 395694 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891301/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891301/master/collection1/data/index;done=false>>]
[junit4] 2> 395695 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891301/master/collection1/data/index
[junit4] 2> 395695 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 395767 T649 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1873329904
[junit4] 2> 395768 T649 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6ce49831
[junit4] 2> 395768 T649 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> 395768 T649 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 395769 T649 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 395769 T649 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 395770 T649 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 395770 T649 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 395770 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891397/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891397/slave/collection1/data/index;done=false>>]
[junit4] 2> 395771 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891397/slave/collection1/data/index
[junit4] 2> 395771 T649 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891397/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891397/slave/collection1/data;done=false>>]
[junit4] 2> 395772 T649 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1377601891397/slave/collection1/data
[junit4] 2> 395772 T649 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 395827 T649 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene45: {newname=MockFixedIntBlock(blockSize=1820), id=MockFixedIntBlock(blockSize=1820), name=MockFixedIntBlock(blockSize=1820)}, docValues:{}, sim=DefaultSimilarity, locale=th, timezone=Greenwich
[junit4] 2> NOTE: Linux 3.2.0-52-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=51492232,total=138977280
[junit4] 2> NOTE: All tests run in this JVM: [RecoveryZkTest, HdfsCollectionsAPIDistributedZkTest, TestSolrDeletionPolicy1, PrimitiveFieldTypeTest, LukeRequestHandlerTest, CacheHeaderTest, TestDynamicFieldCollectionResource, DateMathParserTest, TestLMDirichletSimilarityFactory, EchoParamsTest, AnalysisAfterCoreReloadTest, TestPerFieldSimilarity, ExternalFileFieldSortTest, TestSolrXmlPersistor, SynonymTokenizerTest, SoftAutoCommitTest, BasicDistributedZkTest, TestDocSet, OverseerCollectionProcessorTest, SpellPossibilityIteratorTest, ParsingFieldUpdateProcessorsTest, TestLMJelinekMercerSimilarityFactory, TestSurroundQueryParser, SolrCoreTest, TestReplicationHandler]
[junit4] Completed on J0 in 60.40s, 14 tests, 1 failure <<< FAILURES!
[...truncated 670 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:450: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 325 suites, 1410 tests, 1 failure, 39 ignored (6 assumptions)
Total time: 38 minutes 42 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b102 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure