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 2015/12/12 08:54:13 UTC
[JENKINS-EA] Lucene-Solr-5.x-Linux (32bit/jdk-9-ea+95) - Build #
14877 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/14877/
Java: 32bit/jdk-9-ea+95 -client -XX:+UseConcMarkSweepGC -XX:-CompactStrings
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-011/./collection1/data/index.20151212133511615, /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-011/./collection1/data/index.20151212133511488, /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-011/./collection1/data] expected:<2> but was:<3>
Stack Trace:
java.lang.AssertionError: [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-011/./collection1/data/index.20151212133511615, /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-011/./collection1/data/index.20151212133511488, /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-011/./collection1/data] expected:<2> but was:<3>
at __randomizedtesting.SeedInfo.seed([FEA005ECC419C228:9D3EBB402F16DCE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:815)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1245)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:520)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:747)
Build Log:
[...truncated 10486 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/init-core-data-001
[junit4] 2> 630550 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 630550 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1
[junit4] 2> 630553 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 630560 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13eb612{/solr,null,AVAILABLE}
[junit4] 2> 630564 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Started ServerConnector@13fa9{HTTP/1.1}{127.0.0.1:54213}
[junit4] 2> 630564 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server Started @631935ms
[junit4] 2> 630564 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=54213}
[junit4] 2> 630564 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1eb6432
[junit4] 2> 630564 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001'
[junit4] 2> 630565 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 630565 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 630565 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/solr.xml
[junit4] 2> 630572 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/.
[junit4] 2> 630572 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer New CoreContainer 33226937
[junit4] 2> 630572 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001]
[junit4] 2> 630572 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/lib
[junit4] 2> 630572 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 630573 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 630573 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 630573 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 630574 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 630574 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 630574 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/.
[junit4] 2> 630575 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 630575 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1
[junit4] 2> 630575 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 630575 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1'
[junit4] 2> 630575 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 630575 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 630575 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 630575 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 630583 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 630587 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 630597 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 630600 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 630607 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1'
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/]
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data
[junit4] 2> 630609 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 630609 WARN (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 630611 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index
[junit4] 2> 630611 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=43.65234375, floorSegmentMB=0.337890625, forceMergeDeletesPctAllowed=4.177086638031115, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.560436983723106
[junit4] 2> 630613 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 630613 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 630613 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/
[junit4] 2> 630616 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 630618 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 630619 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 630619 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 630620 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 630623 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 630623 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 630623 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 630623 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 630623 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 630623 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@14514e3[collection1] main
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf}
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf
[junit4] 2> 630625 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 630626 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 630626 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 630626 INFO (searcherExecutor-2441-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14514e3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 630626 INFO (coreLoadExecutor-2440-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 631076 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1
[junit4] 2> 631079 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 631080 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1884344{/solr,null,AVAILABLE}
[junit4] 2> 631080 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Started ServerConnector@10f6cbb{HTTP/1.1}{127.0.0.1:35102}
[junit4] 2> 631080 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server Started @632452ms
[junit4] 2> 631080 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=35102}
[junit4] 2> 631081 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1eb6432
[junit4] 2> 631081 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002'
[junit4] 2> 631081 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 631081 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 631081 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/solr.xml
[junit4] 2> 631095 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/.
[junit4] 2> 631096 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer New CoreContainer 5523586
[junit4] 2> 631096 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002]
[junit4] 2> 631096 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/lib
[junit4] 2> 631096 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 631097 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 631097 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 631097 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 631097 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 631097 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 631098 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/.
[junit4] 2> 631098 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 631098 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1
[junit4] 2> 631098 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 631099 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1'
[junit4] 2> 631099 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 631099 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 631099 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 631099 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 631104 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 631117 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 631132 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 631135 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 631143 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 631145 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1'
[junit4] 2> 631145 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1
[junit4] 2> 631145 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 631145 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/]
[junit4] 2> 631145 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 631146 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data
[junit4] 2> 631146 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 631146 WARN (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 631147 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index
[junit4] 2> 631147 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=43.65234375, floorSegmentMB=0.337890625, forceMergeDeletesPctAllowed=4.177086638031115, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.560436983723106
[junit4] 2> 631148 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 631148 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 631149 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/
[junit4] 2> 631149 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 631150 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631151 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631152 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631152 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631153 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 631153 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 631153 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 631154 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 631154 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 631154 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 631155 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 631155 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 631156 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@f43e20[collection1] main
[junit4] 2> 631156 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/conf
[junit4] 2> 631156 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/conf}
[junit4] 2> 631156 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/conf
[junit4] 2> 631156 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 631158 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 631158 INFO (searcherExecutor-2451-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f43e20[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 631158 INFO (coreLoadExecutor-2450-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 631601 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@10f6cbb{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 631601 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5523586
[junit4] 2> 631601 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8d2454
[junit4] 2> 631602 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DirectUpdateHandler2 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> 631602 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 631602 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 631602 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 631603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 631603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 631603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 631603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index
[junit4] 2> 631603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 631612 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data
[junit4] 2> 631613 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1884344{/solr,null,UNAVAILABLE}
[junit4] 2> 631617 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@13fa9{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 631617 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=33226937
[junit4] 2> 631617 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@429e55
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DirectUpdateHandler2 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> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 631618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data
[junit4] 2> 631619 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 631619 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index
[junit4] 2> 631619 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@13eb612{/solr,null,UNAVAILABLE}
[junit4] 2> 631620 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 631622 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b5accc{/solr,null,AVAILABLE}
[junit4] 2> 631622 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1caf3a3{HTTP/1.1}{127.0.0.1:52113}
[junit4] 2> 631623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server Started @632994ms
[junit4] 2> 631623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=52113}
[junit4] 2> 631623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1eb6432
[junit4] 2> 631623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001'
[junit4] 2> 631623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 631623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 631624 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/solr.xml
[junit4] 2> 631631 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/.
[junit4] 2> 631631 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer New CoreContainer 22841167
[junit4] 2> 631631 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001]
[junit4] 2> 631631 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/lib
[junit4] 2> 631632 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 631633 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 631633 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 631633 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 631633 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 631634 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 631634 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/.
[junit4] 2> 631636 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 631636 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1
[junit4] 2> 631636 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 631636 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1'
[junit4] 2> 631636 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 631637 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 631636 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 631637 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 631643 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 631659 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 631671 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 631674 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 631677 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 631679 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1'
[junit4] 2> 631679 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1
[junit4] 2> 631679 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 631679 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/]
[junit4] 2> 631679 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 631679 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data
[junit4] 2> 631680 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 631680 WARN (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 631680 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index
[junit4] 2> 631680 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=43.65234375, floorSegmentMB=0.337890625, forceMergeDeletesPctAllowed=4.177086638031115, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.560436983723106
[junit4] 2> 631682 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 631682 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 631683 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/
[junit4] 2> 631684 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 631685 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631686 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631687 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631689 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 631690 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 631690 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 631691 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 631691 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 631691 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 631691 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 631692 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 631692 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 631692 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@1bb1c7[collection1] main
[junit4] 2> 631692 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf
[junit4] 2> 631692 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf}
[junit4] 2> 631692 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf
[junit4] 2> 631693 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 631693 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 631693 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 631693 INFO (searcherExecutor-2462-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1bb1c7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 631693 INFO (coreLoadExecutor-2461-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 632142 INFO (qtp7337814-4655) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 632142 INFO (qtp7337814-4655) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 632145 INFO (qtp7337814-4656) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 632146 INFO (qtp7337814-4656) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_2,generation=2}
[junit4] 2> 632146 INFO (qtp7337814-4656) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 632147 INFO (qtp7337814-4656) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@cc4b09[collection1] main
[junit4] 2> 632147 INFO (qtp7337814-4656) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 632147 INFO (searcherExecutor-2462-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cc4b09[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 632147 INFO (qtp7337814-4656) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 632150 INFO (qtp7337814-4657) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 632150 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 632151 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 632152 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@95d916{/solr,null,AVAILABLE}
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4ddaf5{HTTP/1.1}{127.0.0.1:41931}
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.e.j.s.Server Started @633524ms
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=41931}
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1eb6432
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002'
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 632153 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/solr.xml
[junit4] 2> 632159 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/.
[junit4] 2> 632159 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer New CoreContainer 27492812
[junit4] 2> 632159 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002]
[junit4] 2> 632159 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/lib
[junit4] 2> 632159 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 632162 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 632162 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 632162 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 632162 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 632162 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 632163 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/.
[junit4] 2> 632163 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 632164 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1
[junit4] 2> 632164 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 632164 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1'
[junit4] 2> 632164 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 632164 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 632164 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 632164 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 632169 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 632181 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 632191 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 632195 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 632200 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1'
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/]
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data
[junit4] 2> 632201 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 632201 WARN (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 632202 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/index
[junit4] 2> 632202 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=43.65234375, floorSegmentMB=0.337890625, forceMergeDeletesPctAllowed=4.177086638031115, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.560436983723106
[junit4] 2> 632203 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-004 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 632204 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 632204 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/./collection1/data/
[junit4] 2> 632205 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 632205 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632206 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632207 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632207 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632208 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 632208 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 632208 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 632209 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 632209 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 632209 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 632209 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/index-SimpleFSDirectory-004 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1860f22),segFN=segments_1,generation=1}
[junit4] 2> 632209 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 632210 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@677af0[collection1] main
[junit4] 2> 632210 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/conf
[junit4] 2> 632210 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/conf}
[junit4] 2> 632210 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-002/collection1/conf
[junit4] 2> 632210 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 632211 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 632211 INFO (searcherExecutor-2472-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@677af0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 632211 INFO (coreLoadExecutor-2471-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 632667 INFO (qtp4658056-4674) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 632668 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[FEA005ECC419C228]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 632670 INFO (qtp7337814-4655) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 632672 INFO (qtp7337814-4656) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 632674 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1'
[junit4] 2> 632675 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 632675 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 632689 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 632695 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 632710 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 632713 INFO (qtp7337814-4657) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 632716 INFO (qtp7337814-4657) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 632717 INFO (qtp7337814-4657) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1'
[junit4] 2> 632717 INFO (qtp7337814-4657) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1
[junit4] 2> 632719 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/]
[junit4] 2> 632719 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 632719 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 632719 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/./collection1/data/
[junit4] 2> 632720 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 632721 INFO (qtp7337814-4657) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632722 INFO (qtp7337814-4657) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632722 INFO (qtp7337814-4657) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632723 INFO (qtp7337814-4657) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 632724 INFO (qtp7337814-4657) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 632724 INFO (qtp7337814-4657) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 632724 INFO (qtp7337814-4657) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 632724 INFO (qtp7337814-4657) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 632724 INFO (qtp7337814-4657) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 632725 INFO (qtp7337814-4657) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@1b0d6ef[collection1] main
[junit4] 2> 632725 INFO (qtp7337814-4657) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf
[junit4] 2> 632725 INFO (qtp7337814-4657) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf}
[junit4] 2> 632725 INFO (qtp7337814-4657) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-001/collection1/conf
[junit4] 2> 632725 INFO (qtp7337814-4657) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 632725 INFO (qtp73378
[...truncated too long message...]
tartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-027/collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-027/collection1/data;done=false>>]
[junit4] 2> 714371 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-027/collection1/data
[junit4] 2> 714371 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-027/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-027/collection1/data/index;done=false>>]
[junit4] 2> 714371 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-027/collection1/data/index
[junit4] 2> 714371 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1121b95{/solr,null,UNAVAILABLE}
[junit4] 2> 714372 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1a050a8{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 714372 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30634264
[junit4] 2> 714372 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f324ee
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DirectUpdateHandler2 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> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-028/collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-028/collection1/data;done=false>>]
[junit4] 2> 714373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-028/collection1/data
[junit4] 2> 714374 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-028/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-028/collection1/data/index;done=false>>]
[junit4] 2> 714374 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001/solr-instance-028/collection1/data/index
[junit4] 2> 714374 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[FEA005ECC419C228]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@117fcef{/solr,null,UNAVAILABLE}
[junit4] 2> 714376 INFO (SUITE-TestReplicationHandler-seed#[FEA005ECC419C228]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_FEA005ECC419C228-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {name=FSTOrd50, id=FSTOrd50, newname=FSTOrd50}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=el_CY, timezone=Asia/Urumqi
[junit4] 2> NOTE: Linux 3.19.0-37-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=138335480,total=337477632
[junit4] 2> NOTE: All tests run in this JVM: [SolrRequestParserTest, AlternateDirectoryTest, BasicAuthIntegrationTest, TestPseudoReturnFields, JsonLoaderTest, SuggestComponentContextFilterQueryTest, TestRTimerTree, TestSmileRequest, SharedFSAutoReplicaFailoverTest, TestRestManager, JSONWriterTest, TestCryptoKeys, TestRandomDVFaceting, TestRealTimeGet, TestConfigSetImmutable, ResponseLogComponentTest, StandardRequestHandlerTest, TestSchemaVersionResource, PingRequestHandlerTest, TestManagedSynonymFilterFactory, TestInitParams, ZkNodePropsTest, TestBinaryField, TestEmbeddedSolrServerConstructors, TestJoin, FileBasedSpellCheckerTest, TestNoOpRegenerator, ReturnFieldsTest, ResourceLoaderTest, TestOmitPositions, DeleteInactiveReplicaTest, DistributedFacetPivotLongTailTest, HdfsDirectoryFactoryTest, BasicZkTest, SolrCoreTest, TestExpandComponent, ConfigSetsAPITest, BufferStoreTest, TestManagedSchema, SynonymTokenizerTest, TestRandomFaceting, LeaderInitiatedRecoveryOnCommitTest, TestMergePolicyConfig, CollectionTooManyReplicasTest, ParsingFieldUpdateProcessorsTest, RankQueryTest, SolrCloudExampleTest, TestDocBasedVersionConstraints, InfoHandlerTest, TestLazyCores, TestMiniSolrCloudClusterSSL, TestBadConfig, AnalysisAfterCoreReloadTest, AnalyticsQueryTest, TestPhraseSuggestions, HdfsNNFailoverTest, DistributedSpellCheckComponentTest, HdfsWriteToMultipleCollectionsTest, DisMaxRequestHandlerTest, TestDistributedSearch, TestCloudInspectUtil, TestLMDirichletSimilarityFactory, TestStressLucene, TestBlendedInfixSuggestions, TestClassicSimilarityFactory, TestHighlightDedupGrouping, TestExactSharedStatsCache, TestSortingResponseWriter, TestDynamicFieldResource, CopyFieldTest, TestReloadDeadlock, CurrencyFieldOpenExchangeTest, TestReplicationHandler]
[junit4] Completed [229/555 (1!)] on J0 in 83.87s, 13 tests, 1 failure <<< FAILURES!
[...truncated 1042 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:794: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:738: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:526: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1456: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1010: There were test failures: 555 suites (7 ignored), 2192 tests, 1 failure, 83 ignored (38 assumptions) [seed: FEA005ECC419C228]
Total time: 55 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.x-Linux (32bit/jdk1.7.0_80) - Build # 14878
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/14878/
Java: 32bit/jdk1.7.0_80 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([B12FAA0567B0F441:80941430C28FE491]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:749)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:562)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">4</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">14</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
</response>
request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:742)
... 41 more
Build Log:
[...truncated 10065 lines...]
[junit4] Suite: org.apache.solr.spelling.SpellCheckCollatorTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001
[junit4] 2> 476570 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 476570 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 476570 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 476571 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 476571 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 476571 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 476571 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 476588 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 476609 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 476628 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
[junit4] 2> 476634 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 476798 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 476803 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 476805 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 476808 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 476810 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 476811 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 476811 WARN (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 476812 WARN (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 476812 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 476812 WARN (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 476812 WARN (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 476812 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 476812 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 476812 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr'
[junit4] 2> 476812 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 476812 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 476813 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 18639737
[junit4] 2> 476813 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr]
[junit4] 2> 476813 WARN (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib
[junit4] 2> 476813 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 476814 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 476814 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 476814 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 476814 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 476814 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 476815 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, config=solrconfig-spellcheckcomponent.xml, dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001, schema=schema.xml, shard=shard1, loadOnStartup=true, transient=false, collection=collection1, name=collection1}
[junit4] 2> 476815 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 476815 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 476815 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 476815 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 476815 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 476832 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 476842 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 476866 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
[junit4] 2> 476874 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 477040 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 477047 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 477049 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 477055 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 477057 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 477059 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 477060 WARN (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 477060 WARN (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 477060 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 477060 WARN (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 477060 WARN (coreLoadExecutor-974-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/]
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001
[junit4] 2> 477062 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/index/
[junit4] 2> 477062 WARN (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 477063 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/index
[junit4] 2> 477063 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15995259568105782]
[junit4] 2> 477063 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19262e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19ada6e),segFN=segments_1,generation=1}
[junit4] 2> 477063 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 477063 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/
[junit4] 2> 477065 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 477068 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477068 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477069 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477070 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 477071 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /get,/update/json,spellCheckCompRH,spellCheckCompRH1,/update/csv,/replication,/admin/logging,/admin/luke,/admin/plugins,/update/json/docs,/config,/schema,/admin/mbeans,/admin/system,/admin/threads,spellCheckWithWordbreak,/admin/file,/admin/segments,/admin/ping,/update,standard,/admin/properties
[junit4] 2> 477071 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 477071 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 477071 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=94.1181640625, floorSegmentMB=1.544921875, forceMergeDeletesPctAllowed=16.208551953401322, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6684406526363212
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19262e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19ada6e),segFN=segments_1,generation=1}
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@df8194[collection1] main
[junit4] 2> 477072 WARN (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 477072 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 477102 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
[junit4] 2> 477128 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
[junit4] 2> 477128 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
[junit4] 2> 477128 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
[junit4] 2> 477153 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
[junit4] 2> 477153 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=threshold_direct,classname=solr.DirectSolrSpellChecker,field=lowerfilt,spellcheckIndexDir=spellcheckerThreshold,buildOnCommit=true,thresholdTokenFrequency=0.29}
[junit4] 2> 477153 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
[junit4] 2> 477171 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
[junit4] 2> 477226 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
[junit4] 2> 477257 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
[junit4] 2> 477257 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 477257 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 477257 INFO (coreLoadExecutor-974-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 477257 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 477258 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 477258 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 477259 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 477259 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 477259 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 477259 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 477259 INFO (searcherExecutor-975-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@df8194[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 477259 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 1
[junit4] 2> 477264 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 2
[junit4] 2> 477265 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 477268 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 1
[junit4] 2> 477275 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 477277 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 2
[junit4] 2> 477278 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 477281 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 2
[junit4] 2> 477282 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 477284 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 1
[junit4] 2> 477285 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 477287 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 1
[junit4] 2> 477288 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 0
[junit4] 2> 477290 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 1
[junit4] 2> 477291 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 0
[junit4] 2> 477293 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 1
[junit4] 2> 477294 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 0
[junit4] 2> 477295 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 477301 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19262e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19ada6e),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19262e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19ada6e),segFN=segments_2,generation=2}
[junit4] 2> 477301 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 477302 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@a9f551[collection1] main
[junit4] 2> 477302 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 477302 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 477509 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 477706 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 477749 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 477749 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 477910 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 478114 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 478269 INFO (searcherExecutor-975-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a9f551[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):c2) Uninverting(_1(5.5.0):c2) Uninverting(_2(5.5.0):c2) Uninverting(_3(5.5.0):c2) Uninverting(_4(5.5.0):c2) Uninverting(_5(5.5.0):c2) Uninverting(_6(5.5.0):c2) Uninverting(_7(5.5.0):c2) Uninverting(_8(5.5.0):c1)))}
[junit4] 2> 478269 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 974
[junit4] 2> 478272 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEstimatedHitCounts
[junit4] 2> 478282 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia} hits=0 status=0 QTime=9
[junit4] 2> 478292 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=0} hits=0 status=0 QTime=8
[junit4] 2> 478302 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=30} hits=0 status=0 QTime=8
[junit4] 2> 478310 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=100} hits=0 status=0 QTime=7
[junit4] 2> 478318 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10000} hits=0 status=0 QTime=6
[junit4] 2> 478327 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=9} hits=0 status=0 QTime=7
[junit4] 2> 478336 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=7
[junit4] 2> 478353 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=11} hits=0 status=0 QTime=10
[junit4] 2> 478361 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=3} hits=0 status=0 QTime=6
[junit4] 2> 478368 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=11} hits=0 status=0 QTime=5
[junit4] 2> 478375 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=5
[junit4] 2> 478382 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=14} hits=0 status=0 QTime=6
[junit4] 2> 478394 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=6} hits=0 status=0 QTime=10
[junit4] 2> 478399 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=15} hits=0 status=0 QTime=3
[junit4] 2> 478404 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=3
[junit4] 2> 478409 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=7} hits=0 status=0 QTime=3
[junit4] 2> 478414 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=4} hits=0 status=0 QTime=3
[junit4] 2> 478418 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=3
[junit4] 2> 478423 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=3
[junit4] 2> 478427 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=2} hits=0 status=0 QTime=3
[junit4] 2> 478431 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=3} hits=0 status=0 QTime=3
[junit4] 2> 478435 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=3
[junit4] 2> 478440 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=16} hits=0 status=0 QTime=3
[junit4] 2> 478444 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=2} hits=0 status=0 QTime=3
[junit4] 2> 478448 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=3
[junit4] 2> 478453 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=3
[junit4] 2> 478457 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=3
[junit4] 2> 478461 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=4} hits=0 status=0 QTime=3
[junit4] 2> 478465 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=14} hits=0 status=0 QTime=2
[junit4] 2> 478469 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=2
[junit4] 2> 478472 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=9} hits=0 status=0 QTime=2
[junit4] 2> 478476 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=2
[junit4] 2> 478480 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=11} hits=0 status=0 QTime=2
[junit4] 2> 478485 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=3} hits=0 status=0 QTime=4
[junit4] 2> 478492 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=8} hits=0 status=0 QTime=4
[junit4] 2> 478498 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=3
[junit4] 2> 478503 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=4
[junit4] 2> 478509 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=3
[junit4] 2> 478514 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=3
[junit4] 2> 478528 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=8} hits=0 status=0 QTime=4
[junit4] 2> 478533 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=4} hits=0 status=0 QTime=3
[junit4] 2> 478538 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=9} hits=0 status=0 QTime=3
[junit4] 2> 478543 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=14} hits=0 status=0 QTime=3
[junit4] 2> 478548 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=6} hits=0 status=0 QTime=3
[junit4] 2> 478553 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=4} hits=0 status=0 QTime=4
[junit4] 2> 478559 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=4
[junit4] 2> 478560 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">4</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">14</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
[junit4] 2> 478561 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5:java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">4</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">14</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:742)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
[junit4] 2> at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:562)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:606)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 478561 INFO (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEstimatedHitCounts
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SpellCheckCollatorTest -Dtests.method=testEstimatedHitCounts -Dtests.seed=B12FAA0567B0F441 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_DO -Dtests.timezone=Etc/GMT-12 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.29s J1 | SpellCheckCollatorTest.testEstimatedHitCounts <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([B12FAA0567B0F441:80941430C28FE491]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:749)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
[junit4] > at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:562)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">4</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">14</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
[junit4] > </response>
[junit4] > request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:742)
[junit4] > ... 41 more
[junit4] 2> 478568 INFO (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithFilter
[junit4] 2> 478737 INFO (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithFilter
[junit4] 2> 478739 INFO (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithOverride
[junit4] 2> 478751 INFO (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qf=teststop&qt=spellCheckCompRH&defType=edismax&spellcheck.maxCollationTries=10&wt=xml&mm=1&spellcheck.count=10&spellcheck.maxCollations=10&q=partisian+politcal+mashine&spellcheck.collate=true&spellcheck.dictionary=direct&spellcheck=true} hits=0 status=0 QTime=11
[junit4] 2> 478764 INFO (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qf=teststop&spellcheck.maxCollationTries=10&wt=xml&spellcheck.count=10&spellcheck.maxCollations=10&spellcheck.collateParam.mm=100%25&spellcheck.collate=true&q=partisian+politcal+mashine&qt=spellCheckCompRH&defType=edismax&mm=1&spellcheck=true&spellcheck.dictionary=direct} hits=0 status=0 QTime=11
[junit4] 2> 478765 INFO (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithOverride
[junit4] 2> 478770 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testContextSensitiveCollate
[junit4] 2> 478776 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.maxCollationTries=10&wt=xml&spellcheck.count=10&spellcheck.maxCollations=1&spellcheck.maxResultsForSuggest=0&spellcheck.collateExtendedResults=true&spellcheck.build=true&q=teststop:(flew+AND+form+AND+heathrow)&spellcheck.extendedResults=true&spellcheck.collate=true&qt=spellCheckCompRH&indent=true&spellcheck.alternativeTermCount=5&spellcheck=true&spellcheck.dictionary=direct} hits=0 status=0 QTime=6
[junit4] 2> 478786 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.maxCollationTries=10&wt=xml&spellcheck.count=10&spellcheck.maxCollations=1&spellcheck.maxResultsForSuggest=1&spellcheck.collateExtendedResults=true&q=teststop:(june+AND+customs)&spellcheck.extendedResults=true&spellcheck.collate=true&qt=spellCheckCompRH&indent=true&spellcheck.alternativeTermCount=5&spellcheck=true&spellcheck.dictionary=direct} hits=1 status=0 QTime=4
[junit4] 2> 478791 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=spellCheckCompRH&wt=xml&indent=true&mm=2&spellcheck.count=10&spellcheck.alternativeTermCount=0&spellcheck.collate=true&q=teststop:(june+customs)&spellcheck.dictionary=direct&spellcheck=true} hits=2 status=0 QTime=1
[junit4] 2> 479027 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.maxCollationTries=10&wt=xml&spellcheck.count=10&spellcheck.maxCollations=1&spellcheck.maxResultsForSuggest=0&spellcheck.collateExtendedResults=true&spellcheck.build=true&q=teststop:(flew+AND+form+AND+heathrow)&spellcheck.extendedResults=true&spellcheck.collate=true&qt=spellCheckCompRH&indent=true&spellcheck.alternativeTermCount=5&spellcheck=true&spellcheck.dictionary=default_teststop} hits=0 status=0 QTime=235
[junit4] 2> 479036 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.maxCollationTries=10&wt=xml&spellcheck.count=10&spellcheck.maxCollations=1&spellcheck.maxResultsForSuggest=1&spellcheck.collateExtendedResults=true&q=teststop:(june+AND+customs)&spellcheck.extendedResults=true&spellcheck.collate=true&qt=spellCheckCompRH&indent=true&spellcheck.alternativeTermCount=5&spellcheck=true&spellcheck.dictionary=default_teststop} hits=1 status=0 QTime=4
[junit4] 2> 479051 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=spellCheckCompRH&wt=xml&indent=true&mm=2&spellcheck.count=10&spellcheck.alternativeTermCount=0&spellcheck.collate=true&q=teststop:(june+customs)&spellcheck.dictionary=default_teststop&spellcheck=true} hits=2 status=0 QTime=1
[junit4] 2> 479051 INFO (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testContextSensitiveCollate
[junit4] 2> 479055 INFO (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithMultipleRequestHandlers
[junit4] 2> 479179 INFO (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithMultipleRequestHandlers
[junit4] 2> 479181 INFO (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithRangeQuery
[junit4] 1> id:[1 TO 10] AND lowerfilt:love
[junit4] 2> 479234 INFO (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithRangeQuery
[junit4] 2> 479236 INFO (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedCollate
[junit4] 2> 479335 INFO (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedCollate
[junit4] 2> 479338 INFO (TEST-SpellCheckCollatorTest.testZeroTries-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZeroTries
[junit4] 2> 479403 INFO (TEST-SpellCheckCollatorTest.testZeroTries-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZeroTries
[junit4] 2> 479406 INFO (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithHypens
[junit4] 2> 479627 INFO (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithHypens
[junit4] 2> 479629 INFO (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithGrouping
[junit4] 2> 479748 INFO (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[B12FAA0567B0F441]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithGrouping
[junit4] 2> 479748 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 479748 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18639737
[junit4] 2> 479748 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@860964
[junit4] 2> 479748 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.u.DirectUpdateHandler2 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=17,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001;done=false>>]
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001
[junit4] 2> 479749 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/index;done=false>>]
[junit4] 2> 479750 INFO (SUITE-SpellCheckCollatorTest-seed#[B12FAA0567B0F441]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_B12FAA0567B0F441-001
[junit4] 2> NOTE: test params are: codec=Lucene54, sim=DefaultSimilarity, locale=es_DO, timezone=Etc/GMT-12
[junit4] 2> NOTE: Linux 3.19.0-37-generic i386/Oracle Corporation 1.7.0_80 (32-bit)/cpus=12,threads=1,free=48017160,total=179146752
[junit4] 2> NOTE: All tests run in this JVM: [TestReRankQParserPlugin, CursorMarkTest, OpenExchangeRatesOrgProviderTest, SuggestComponentTest, CoreAdminRequestStatusTest, DistanceFunctionTest, SolrCmdDistributorTest, ShardRoutingCustomTest, DirectSolrSpellCheckerTest, AddSchemaFieldsUpdateProcessorFactoryTest, LeaderInitiatedRecoveryOnShardRestartTest, TestManagedResource, DistribCursorPagingTest, TestRawTransformer, ZkStateWriterTest, HighlighterTest, TestSchemaResource, TestSchemaManager, WordBreakSolrSpellCheckerTest, TestDefaultSearchFieldResource, TestChildDocTransformer, TestSolr4Spatial2, TestBinaryResponseWriter, TestUniqueKeyFieldResource, CollectionsAPIAsyncDistributedZkTest, ChaosMonkeySafeLeaderTest, TestPartialUpdateDeduplication, TestDistribDocBasedVersion, SimplePostToolTest, DistributedQueueTest, DocExpirationUpdateProcessorFactoryTest, TestRandomForceMergePolicy, HdfsChaosMonkeySafeLeaderTest, TestCloudPivotFacet, HttpPartitionTest, TestWordDelimiterFilterFactory, SolrIndexSplitterTest, TestTrie, TestSolrDeletionPolicy2, NotRequiredUniqueKeyTest, PKIAuthenticationIntegrationTest, PreAnalyzedUpdateProcessorTest, TestSystemIdResolver, RestartWhileUpdatingTest, TestFiltering, TestSearcherReuse, SpellCheckCollatorTest]
[junit4] Completed [142/555 (1!)] on J1 in 3.20s, 10 tests, 1 error <<< FAILURES!
[...truncated 1316 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:794: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:738: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:526: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1456: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1010: There were test failures: 555 suites (7 ignored), 2192 tests, 1 error, 82 ignored (38 assumptions) [seed: B12FAA0567B0F441]
Total time: 54 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any