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 2016/08/02 21:51:42 UTC
[JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_102) - Build # 1349
- Unstable!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1349/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Expected to find shardAddress in the up shard info
Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info
at __randomizedtesting.SeedInfo.seed([B726ADD1EEFDF30:83265507B013B2C8]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
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:498)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
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)
Build Log:
[...truncated 11813 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/init-core-data-001
[junit4] 2> 1029054 INFO (SUITE-TestDistributedSearch-seed#[B726ADD1EEFDF30]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061)
[junit4] 2> 1029054 INFO (SUITE-TestDistributedSearch-seed#[B726ADD1EEFDF30]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1029158 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1
[junit4] 2> 1029159 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1029160 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a42967e{/,null,AVAILABLE}
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1d1d2e7c{HTTP/1.1,[http/1.1]}{127.0.0.1:42584}
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.Server Started @1030766ms
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=42584, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores}
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control'
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1029161 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/solr.xml
[junit4] 2> 1029166 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores
[junit4] 2> 1029167 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer New CoreContainer 1412636539
[junit4] 2> 1029167 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control]
[junit4] 2> 1029167 WARN (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/lib
[junit4] 2> 1029167 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] 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,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1029169 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1029169 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1029169 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1029169 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1029169 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1029169 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores
[junit4] 2> 1029176 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/, configSet=collection1}
[junit4] 2> 1029177 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1
[junit4] 2> 1029177 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1029177 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1029177 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1029177 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1029177 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1029178 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1029178 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1029178 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1029191 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1029200 WARN (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1029201 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
[junit4] 2> 1029232 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1029235 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1029313 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1029317 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1029318 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1029320 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1029321 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1029322 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1029322 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1029323 INFO (coreLoadExecutor-3345-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-6.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1029323 INFO (coreLoadExecutor-3345-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1029323 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1029323 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1029323 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d6ca5c7
[junit4] 2> 1029324 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/snapshot_metadata
[junit4] 2> 1029324 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 1029324 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 1029324 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 1029324 WARN (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1029324 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 1029324 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=6.7001953125, floorSegmentMB=1.171875, forceMergeDeletesPctAllowed=13.434201426207363, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1029325 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45c6fcd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@458aba8b),segFN=segments_1,generation=1}
[junit4] 2> 1029325 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1029325 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-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/
[junit4] 2> 1029325 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/control/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1029327 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1029329 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029329 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029330 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029330 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029332 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1029333 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 1029333 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1029333 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1029333 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37336316251963175]
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45c6fcd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@458aba8b),segFN=segments_1,generation=1}
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3679e48c[collection1] main]
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1029334 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1029365 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1029484 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1029484 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1029484 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1029485 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1029485 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1029485 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1029485 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1541588220755574784
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1029485 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1029486 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3679e48c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1029487 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1541588220755574784
[junit4] 2> 1029487 INFO (coreLoadExecutor-3345-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1029762 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1029763 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1029765 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@233c0151{/,null,AVAILABLE}
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.ServerConnector Started ServerConnector@126ef36b{HTTP/1.1,[http/1.1]}{127.0.0.1:43571}
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.Server Started @1031372ms
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=43571, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores}
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0'
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1029766 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1029767 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1029771 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores
[junit4] 2> 1029771 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer New CoreContainer 1181908877
[junit4] 2> 1029771 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0]
[junit4] 2> 1029772 WARN (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/lib
[junit4] 2> 1029772 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] 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,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1029773 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1029774 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1029774 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1029774 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1029774 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1029774 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores
[junit4] 2> 1029775 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/, configSet=collection1}
[junit4] 2> 1029775 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1029775 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1029775 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1029776 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1029776 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1029776 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1029776 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1029776 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1029776 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1029790 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1029797 WARN (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1029798 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
[junit4] 2> 1029815 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1029819 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1029904 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1029908 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1029909 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1029911 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1029912 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1029913 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1029913 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1029914 INFO (coreLoadExecutor-3356-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-6.x-Linux/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1029914 INFO (coreLoadExecutor-3356-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1029914 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1029914 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1029914 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d6ca5c7
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/snapshot_metadata
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 1029915 WARN (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=6.7001953125, floorSegmentMB=1.171875, forceMergeDeletesPctAllowed=13.434201426207363, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@73850af7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebb1b9f),segFN=segments_1,generation=1}
[junit4] 2> 1029915 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1029916 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-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/
[junit4] 2> 1029916 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard0/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1029918 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1029919 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1029919 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1029919 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1029919 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1029919 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1029920 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029921 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029921 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029922 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1029926 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1029927 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 1029927 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1029927 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1029927 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37336316251963175]
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@73850af7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebb1b9f),segFN=segments_1,generation=1}
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2651283f[collection1] main]
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1029928 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1029954 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1030085 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1030085 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1030085 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1030085 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1030085 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1030085 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1030086 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1030086 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1030086 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1030086 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1030086 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1541588221385768960
[junit4] 2> 1030086 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1030086 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1030086 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1030086 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2651283f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1030087 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1541588221385768960
[junit4] 2> 1030087 INFO (coreLoadExecutor-3356-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1030277 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1030279 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1541588221587095552)} 0 1
[junit4] 2> 1030281 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1541588221589192704)} 0 0
[junit4] 2> 1030283 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1541588221591289856)]} 0 1
[junit4] 2> 1030285 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1541588221594435584)]} 0 0
[junit4] 2> 1030287 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1541588221596532736)]} 0 0
[junit4] 2> 1030289 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1541588221597581312)]} 0 0
[junit4] 2> 1030290 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1541588221599678464)]} 0 0
[junit4] 2> 1030292 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1541588221601775616)]} 0 0
[junit4] 2> 1030294 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1541588221603872768)]} 0 0
[junit4] 2> 1030296 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1541588221604921344)]} 0 0
[junit4] 2> 1030298 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1541588221608067072)]} 0 0
[junit4] 2> 1030300 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1541588221610164224)]} 0 0
[junit4] 2> 1030301 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1541588221611212800)]} 0 0
[junit4] 2> 1030303 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1541588221613309952)]} 0 0
[junit4] 2> 1030305 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1541588221615407104)]} 0 0
[junit4] 2> 1030306 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1541588221616455680)]} 0 0
[junit4] 2> 1030309 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1541588221619601408)]} 0 0
[junit4] 2> 1030311 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1541588221621698560)]} 0 0
[junit4] 2> 1030313 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1541588221622747136)]} 0 0
[junit4] 2> 1030314 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1541588221624844288)]} 0 0
[junit4] 2> 1030316 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1030319 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45c6fcd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@458aba8b),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45c6fcd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@458aba8b),segFN=segments_2,generation=2}
[junit4] 2> 1030319 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1030319 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27d245b[collection1] main]
[junit4] 2> 1030320 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1030321 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27d245b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9)))}
[junit4] 2> 1030321 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 1030323 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1030325 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@73850af7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebb1b9f),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@73850af7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebb1b9f),segFN=segments_2,generation=2}
[junit4] 2> 1030325 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1030325 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@586ac33b[collection1] main]
[junit4] 2> 1030326 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1030326 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@586ac33b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9)))}
[junit4] 2> 1030326 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1030330 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1541588221639524352)]} 0 1
[junit4] 2> 1030333 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1541588221642670080)]} 0 1
[junit4] 2> 1030335 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1541588221645815808)]} 0 0
[junit4] 2> 1030337 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1541588221647912960)]} 0 0
[junit4] 2> 1030338 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1541588221650010112)]} 0 0
[junit4] 2> 1030340 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1541588221652107264)]} 0 0
[junit4] 2> 1030342 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1541588221653155840)]} 0 0
[junit4] 2> 1030343 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1541588221655252992)]} 0 0
[junit4] 2> 1030345 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1541588221657350144)]} 0 0
[junit4] 2> 1030347 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1541588221659447296)]} 0 0
[junit4] 2> 1030349 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1541588221660495872)]} 0 0
[junit4] 2> 1030350 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1541588221662593024)]} 0 0
[junit4] 2> 1030352 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1541588221663641600)]} 0 0
[junit4] 2> 1030353 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1541588221665738752)]} 0 0
[junit4] 2> 1030355 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1541588221667835904)]} 0 0
[junit4] 2> 1030357 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1541588221669933056)]} 0 0
[junit4] 2> 1030359 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1541588221672030208)]} 0 0
[junit4] 2> 1030361 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1541588221673078784)]} 0 0
[junit4] 2> 1030362 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1541588221675175936)]} 0 0
[junit4] 2> 1030364 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1541588221676224512)]} 0 0
[junit4] 2> 1030365 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1541588221678321664)]} 0 0
[junit4] 2> 1030367 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1541588221679370240)]} 0 0
[junit4] 2> 1030368 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1541588221681467392)]} 0 0
[junit4] 2> 1030370 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1541588221683564544)]} 0 0
[junit4] 2> 1030371 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1541588221684613120)]} 0 0
[junit4] 2> 1030373 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1541588221686710272)]} 0 0
[junit4] 2> 1030374 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1541588221687758848)]} 0 0
[junit4] 2> 1030376 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1541588221689856000)]} 0 0
[junit4] 2> 1030377 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1541588221690904576)]} 0 0
[junit4] 2> 1030379 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1541588221693001728)]} 0 0
[junit4] 2> 1030381 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1541588221695098880)]} 0 0
[junit4] 2> 1030383 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1541588221696147456)]} 0 0
[junit4] 2> 1030384 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1541588221698244608)]} 0 0
[junit4] 2> 1030386 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1541588221700341760)]} 0 0
[junit4] 2> 1030387 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1541588221701390336)]} 0 0
[junit4] 2> 1030389 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1541588221703487488)]} 0 0
[junit4] 2> 1030390 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1541588221704536064)]} 0 0
[junit4] 2> 1030393 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1541588221706633216)]} 0 1
[junit4] 2> 1030394 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1541588221708730368)]} 0 0
[junit4] 2> 1030397 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1541588221710827520)]} 0 0
[junit4] 2> 1030398 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1541588221712924672)]} 0 0
[junit4] 2> 1030400 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1541588221715021824)]} 0 0
[junit4] 2> 1030401 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1541588221716070400)]} 0 0
[junit4] 2> 1030403 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1541588221718167552)]} 0 0
[junit4] 2> 1030405 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1541588221720264704)]} 0 0
[junit4] 2> 1030407 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1541588221721313280)]} 0 0
[junit4] 2> 1030408 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1541588221723410432)]} 0 0
[junit4] 2> 1030410 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1541588221724459008)]} 0 0
[junit4] 2> 1030411 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1541588221726556160)]} 0 0
[junit4] 2> 1030413 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1541588221727604736)]} 0 0
[junit4] 2> 1030414 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1541588221729701888)]} 0 0
[junit4] 2> 1030417 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1541588221732847616)]} 0 0
[junit4] 2> 1030418 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1541588221733896192)]} 0 0
[junit4] 2> 1030420 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1541588221735993344)]} 0 0
[junit4] 2> 1030422 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1541588221737041920)]} 0 0
[junit4] 2> 1030423 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1541588221739139072)]} 0 0
[junit4] 2> 1030425 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1541588221741236224)]} 0 0
[junit4] 2> 1030426 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1541588221742284800)]} 0 0
[junit4] 2> 1030429 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1541588221744381952)]} 0 1
[junit4] 2> 1030430 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1541588221746479104)]} 0 0
[junit4] 2> 1030432 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1541588221748576256)]} 0 0
[junit4] 2> 1030433 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1541588221749624832)]} 0 0
[junit4] 2> 1030435 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1541588221751721984)]} 0 0
[junit4] 2> 1030437 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1541588221752770560)]} 0 0
[junit4] 2> 1030438 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1541588221754867712)]} 0 0
[junit4] 2> 1030441 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1541588221756964864)]} 0 1
[junit4] 2> 1030442 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1541588221759062016)]} 0 0
[junit4] 2> 1030444 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1541588221761159168)]} 0 0
[junit4] 2> 1030445 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1541588221762207744)]} 0 0
[junit4] 2> 1030447 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1541588221764304896)]} 0 0
[junit4] 2> 1030449 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1541588221765353472)]} 0 0
[junit4] 2> 1030450 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1541588221767450624)]} 0 0
[junit4] 2> 1030453 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1541588221769547776)]} 0 1
[junit4] 2> 1030454 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1541588221771644928)]} 0 0
[junit4] 2> 1030456 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1541588221773742080)]} 0 0
[junit4] 2> 1030458 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1541588221774790656)]} 0 0
[junit4] 2> 1030459 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1541588221776887808)]} 0 0
[junit4] 2> 1030461 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1541588221777936384)]} 0 0
[junit4] 2> 1030462 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1541588221780033536)]} 0 0
[junit4] 2> 1030464 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1541588221781082112)]} 0 0
[junit4] 2> 1030466 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1541588221784227840)]} 0 0
[junit4] 2> 1030468 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1541588221785276416)]} 0 0
[junit4] 2> 1030469 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1541588221787373568)]} 0 0
[junit4] 2> 1030471 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1541588221789470720)]} 0 0
[junit4] 2> 1030472 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1541588221790519296)]} 0 0
[junit4] 2> 1030474 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1541588221792616448)]} 0 0
[junit4] 2> 1030475 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1541588221793665024)]} 0 0
[junit4] 2> 1030477 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1541588221795762176)]} 0 0
[junit4] 2> 1030478 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1541588221796810752)]} 0 0
[junit4] 2> 1030480 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1541588221798907904)]} 0 0
[junit4] 2> 1030482 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1541588221799956480)]} 0 0
[junit4] 2> 1030483 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1541588221802053632)]} 0 0
[junit4] 2> 1030485 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1541588221803102208)]} 0 0
[junit4] 2> 1030486 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1541588221805199360)]} 0 0
[junit4] 2> 1030489 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1541588221808345088)]} 0 1
[junit4] 2> 1030490 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1541588221809393664)]} 0 0
[junit4] 2> 1030492 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1541588221811490816)]} 0 0
[junit4] 2> 1030494 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1541588221812539392)]} 0 0
[junit4] 2> 1030495 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1541588221814636544)]} 0 0
[junit4] 2> 1030497 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1541588221815685120)]} 0 0
[junit4] 2> 1030498 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1541588221817782272)]} 0 0
[junit4] 2> 1030501 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1541588221819879424)]} 0 1
[junit4] 2> 1030502 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1541588221821976576)]} 0 0
[junit4] 2> 1030504 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1541588221823025152)]} 0 0
[junit4] 2> 1030505 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1541588221825122304)]} 0 0
[junit4] 2> 1030506 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1541588221826170880)]} 0 0
[junit4] 2> 1030508 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1541588221828268032)]} 0 0
[junit4] 2> 1030509 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1541588221829316608)]} 0 0
[junit4] 2> 1030511 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1541588221831413760)]} 0 0
[junit4] 2> 1030513 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1541588221833510912)]} 0 0
[junit4] 2> 1030514 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1541588221834559488)]} 0 0
[junit4] 2> 1030516 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1541588221836656640)]} 0 0
[junit4] 2> 1030517 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1541588221837705216)]} 0 0
[junit4] 2> 1030519 INFO (qtp1452856271-8871) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1541588221839802368)]} 0 0
[junit4] 2> 1030520 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1541588221840850944)]} 0 0
[junit4] 2> 1030522 INFO (qtp1452856271-8876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1541588221842948096)]} 0 0
[junit4] 2> 1030523 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1541588221843996672)]} 0 0
[junit4] 2> 1030525 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1541588221846093824)]} 0 0
[junit4] 2> 1030526 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1030531 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45c6fcd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@458aba8b),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@45c6fcd8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@458aba8b),segFN=segments_3,generation=3}
[junit4] 2> 1030531 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1030532 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7fe86f89[collection1] main]
[junit4] 2> 1030532 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1030533 INFO (searcherExecutor-3346-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7fe86f89[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9) Uninverting(_1(6.2.0):C59)))}
[junit4] 2> 1030533 INFO (qtp1123601544-8861) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6
[junit4] 2> 1030535 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1030542 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@73850af7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebb1b9f),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@73850af7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ebb1b9f),segFN=segments_3,generation=3}
[junit4] 2> 1030542 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1030542 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@120c9191[collection1] main]
[junit4] 2> 1030542 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1030543 INFO (searcherExecutor-3357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@120c9191[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9) Uninverting(_1(6.2.0):C59)))}
[junit4] 2> 1030543 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 1030545 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1030545 INFO (qtp1123601544-8854) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1030547 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1030547 INFO (qtp1123601544-8859) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1030549 INFO (qtp1123601544-8860) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1030555 INFO (qtp1452856271-8877) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:43571//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1470173093675&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1030557 INFO (qtp1452856271-8878) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1470173093675&ids=110,101,146,124,102,14,126,128,140,142&isShard=true&shard.url=http://127.0.0.1:43571//collection1|[ff01::083]:33332/|[ff01::213]:3333
[...truncated too long message...]
2> 1135524 INFO (coreCloseExecutor-3453-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard1/cores/collection1/data
[junit4] 2> 1135526 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3eaab9f{/,null,UNAVAILABLE}
[junit4] 2> 1135526 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@b20d8db{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1135526 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=85324316
[junit4] 2> 1135527 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@49daa4bc
[junit4] 2> 1135527 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=23,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=4287,transaction_logs_total_number=3}
[junit4] 2> 1135527 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1135527 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1135527 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1135528 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1135536 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data;done=false>>]
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data/snapshot_metadata [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data/snapshot_metadata;done=false>>]
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data/snapshot_metadata
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data/index;done=false>>]
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1135537 INFO (coreCloseExecutor-3455-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001/tempDir-001/shard2/cores/collection1/data/index
[junit4] 2> 1135538 INFO (TEST-TestDistributedSearch.test-seed#[B726ADD1EEFDF30]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@27ce79ca{/,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=B726ADD1EEFDF30 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hi -Dtests.timezone=ROK -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 106s J1 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected to find shardAddress in the up shard info
[junit4] > at __randomizedtesting.SeedInfo.seed([B726ADD1EEFDF30:83265507B013B2C8]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1135544 INFO (SUITE-TestDistributedSearch-seed#[B726ADD1EEFDF30]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_B726ADD1EEFDF30-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=ClassicSimilarity, locale=hi, timezone=ROK
[junit4] 2> NOTE: Linux 4.4.0-31-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=2,free=211982304,total=530579456
[junit4] 2> NOTE: All tests run in this JVM: [TestRequestForwarding, TestSchemaSimilarityResource, TestExactStatsCache, DistributedFacetPivotLongTailTest, CollectionReloadTest, SpatialFilterTest, PKIAuthenticationIntegrationTest, SolrInfoMBeanTest, TestTolerantUpdateProcessorCloud, DistributedQueueTest, InfoHandlerTest, HdfsBasicDistributedZkTest, TestStressVersions, BasicFunctionalityTest, TestUpdate, TestCursorMarkWithoutUniqueKey, TestQueryTypes, TestFieldCache, OverseerModifyCollectionTest, TestInitParams, BigEndianAscendingWordSerializerTest, TestDFRSimilarityFactory, SpellingQueryConverterTest, DistanceFunctionTest, DistributedTermsComponentTest, DistributedIntervalFacetingTest, DocValuesTest, BooleanFieldTest, BlockJoinFacetDistribTest, FullHLLTest, CustomCollectionTest, WrapperMergePolicyFactoryTest, TestDefaultSearchFieldResource, CollectionsAPIAsyncDistributedZkTest, TestSearcherReuse, TestEmbeddedSolrServerConstructors, GraphQueryTest, SolrIndexSplitterTest, TestFieldCacheWithThreads, SharedFSAutoReplicaFailoverTest, TestCodecSupport, SolrIndexConfigTest, DistribCursorPagingTest, CSVRequestHandlerTest, ZkCLITest, TestOrdValues, TestCollapseQParserPlugin, TestSystemIdResolver, TestQueryWrapperFilter, HttpPartitionTest, TestSolrCloudWithKerberosAlt, UniqFieldsUpdateProcessorFactoryTest, TestCryptoKeys, JavabinLoaderTest, TestBM25SimilarityFactory, WordBreakSolrSpellCheckerTest, TestClassicSimilarityFactory, TestShortCircuitedRequests, TestBlobHandler, DocValuesMissingTest, RuleEngineTest, OpenCloseCoreStressTest, DistributedDebugComponentTest, OverseerTaskQueueTest, CursorMarkTest, TestLMJelinekMercerSimilarityFactory, SpellPossibilityIteratorTest, SpellCheckCollatorTest, TestSQLHandler, TestSubQueryTransformer, LeaderInitiatedRecoveryOnShardRestartTest, TestQuerySenderNoQuery, TestMacros, SparseHLLTest, ClassificationUpdateProcessorFactoryTest, AnalysisErrorHandlingTest, MinimalSchemaTest, TestDocumentBuilder, TestSolrCoreProperties, OpenExchangeRatesOrgProviderTest, QueryResultKeyTest, MBeansHandlerTest, TestFieldTypeResource, TestNamedUpdateProcessors, TestSolrQueryResponse, TestGraphMLResponseWriter, TestSolr4Spatial2, ZkSolrClientTest, SuggesterFSTTest, SpellCheckComponentTest, TestSurroundQueryParser, SoftAutoCommitTest, LoggingHandlerTest, TestManagedResource, TestBulkSchemaAPI, TimeZoneUtilsTest, TestExpandComponent, TestCloudSchemaless, DOMUtilTest, TestStressUserVersions, TestExclusionRuleCollectionAccess, NotRequiredUniqueKeyTest, TestShardHandlerFactory, OverseerTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestRandomFaceting, ShardRoutingCustomTest, TestDistributedSearch]
[junit4] Completed [377/626 (1!)] on J1 in 106.51s, 1 test, 1 failure <<< FAILURES!
[...truncated 60913 lines...]