Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/755/
Java: 32bit/jdk1.8.0_121 -server -XX:+UseG1GC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.search.MergeStrategyTest
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\collection1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\collection1
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\collection1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\collection1
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001
at __randomizedtesting.SeedInfo.seed([5F947E1F84CBBD7E]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
at
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12986 lines...]
[junit4] Suite: org.apache.solr.search.MergeStrategyTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\init-core-data-001
[junit4] 2> 2672570 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 2672577 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2672579 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2672579 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2672580 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2672647 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2672686 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2672736 WARN
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 2672737 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2672750 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 2672851 WARN
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 2672853 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2672853 WARN
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 2672857 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2672857 WARN
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 2672857 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2672859 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2672929 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2672948 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2673013 WARN (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema
is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2673014 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2673032 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2673033 WARN (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was
configured, using first MBeanServer.
[junit4] 2> 2673033 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2673033 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\init-core-data-001\]
[junit4] 2> 2673033 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2673035 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=19.1904296875,
floorSegmentMB=0.5712890625, forceMergeDeletesPctAllowed=27.22354795804939,
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4061384562511272
[junit4] 2> 2673162 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2673163 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2673164 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2673164 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2673164 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2673166 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@820499[collection1]
main]
[junit4] 2> 2673166 WARN (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf;
switching to use InMemory storage instead.
[junit4] 2> 2673166 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2673173 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2673214 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml
[junit4] 2> 2673227 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2673227 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2673228 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2673228 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2673228 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2673228 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2673228 INFO (coreLoadExecutor-11116-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1560518102323560448
[junit4] 2> 2673229 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2673229 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2673230 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2673230 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2673231 INFO
(searcherExecutor-11117-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@820499[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2673237 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2673862 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\control\cores\collection1
[junit4] 2> 2673867 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 2673868 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@780594{/,null,AVAILABLE}
[junit4] 2> 2673869 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@a45b6{SSL,[ssl,
http/1.1]}{127.0.0.1:63508}
[junit4] 2> 2673869 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
Started @2678854ms
[junit4] 2> 2673869 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema15.xml,
solrconfig=solrconfig-plugcollector.xml, hostContext=/, hostPort=63508,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\control\cores}
[junit4] 2> 2673869 ERROR
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2673870 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 2673872 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 2673872 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2673872 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-27T20:07:15.117Z
[junit4] 2> 2673873 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\control\solr.xml
[junit4] 2> 2673888 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2673992 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\control\cores
[junit4] 2> 2673992 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2673994 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2674062 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2674081 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2674147 WARN (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema
is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2674149 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2674167 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from configset
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2674167 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 2674167 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2674169 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=19.1904296875,
floorSegmentMB=0.5712890625, forceMergeDeletesPctAllowed=27.22354795804939,
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4061384562511272
[junit4] 2> 2674300 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2674300 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2674301 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2674301 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2674302 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2674303 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@859ddf[collection1]
main]
[junit4] 2> 2674304 WARN (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf;
switching to use InMemory storage instead.
[junit4] 2> 2674304 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2674313 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2674357 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml
[junit4] 2> 2674369 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2674370 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2674370 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2674370 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2674370 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2674371 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2674371 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2674371 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2674372 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2674372 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2674374 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@859ddf[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2674374 INFO (coreLoadExecutor-11127-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1560518103525228544
[junit4] 2> 2675527 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 2675536 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 2675537 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@793de9{/,null,AVAILABLE}
[junit4] 2> 2675538 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1e740cb{SSL,[ssl,
http/1.1]}{127.0.0.1:63511}
[junit4] 2> 2675538 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
Started @2680523ms
[junit4] 2> 2675538 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema15.xml,
solrconfig=solrconfig-plugcollector.xml, hostContext=/, hostPort=63511,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard0\cores}
[junit4] 2> 2675538 ERROR
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2675539 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 2675539 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 2675539 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2675539 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-27T20:07:16.784Z
[junit4] 2> 2675540 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard0\solr.xml
[junit4] 2> 2675556 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2675671 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard0\cores
[junit4] 2> 2675672 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2675674 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2675800 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2675821 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2675889 WARN (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema
is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2675890 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2675909 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from configset
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2675909 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 2675909 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2675949 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=19.1904296875,
floorSegmentMB=0.5712890625, forceMergeDeletesPctAllowed=27.22354795804939,
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4061384562511272
[junit4] 2> 2676085 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2676085 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2676086 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2676086 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2676088 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2676090 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b97c5[collection1]
main]
[junit4] 2> 2676091 WARN (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf;
switching to use InMemory storage instead.
[junit4] 2> 2676091 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2676102 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2676164 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml
[junit4] 2> 2676176 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2676180 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2676180 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2676180 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2676180 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2676182 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2676182 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2676182 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2676182 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2676183 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2676185 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5b97c5[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2676190 INFO (coreLoadExecutor-11138-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1560518105429442560
[junit4] 2> 2677376 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\cores\collection1
[junit4] 2> 2677381 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 2677382 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e6981a{/,null,AVAILABLE}
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@73ccf6{SSL,[ssl,
http/1.1]}{127.0.0.1:63514}
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
Started @2682367ms
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema15.xml,
solrconfig=solrconfig-plugcollector.xml, hostContext=/, hostPort=63514,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\cores}
[junit4] 2> 2677383 ERROR
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2677383 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-27T20:07:18.628Z
[junit4] 2> 2677384 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\solr.xml
[junit4] 2> 2677402 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2677519 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\cores
[junit4] 2> 2677519 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2677565 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2677630 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2677685 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2677734 WARN (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema
is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2677736 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2677753 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from configset
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2677754 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\cores\collection1\data\]
[junit4] 2> 2677754 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2677761 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=19.1904296875,
floorSegmentMB=0.5712890625, forceMergeDeletesPctAllowed=27.22354795804939,
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4061384562511272
[junit4] 2> 2677889 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2677889 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2677890 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2677891 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2677894 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2677894 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4961f0[collection1]
main]
[junit4] 2> 2677895 WARN (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf;
switching to use InMemory storage instead.
[junit4] 2> 2677895 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2677928 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2677975 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml
[junit4] 2> 2677993 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2677993 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2677994 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2677994 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2677994 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2677994 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2677994 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2677995 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2677995 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2677996 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2677997 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4961f0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2678005 INFO (coreLoadExecutor-11149-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1560518107332608000
[junit4] 2> 2679081 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard2\cores\collection1
[junit4] 2> 2679085 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 2679086 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@ef506b{/,null,AVAILABLE}
[junit4] 2> 2679087 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@24dc5c{SSL,[ssl,
http/1.1]}{127.0.0.1:63517}
[junit4] 2> 2679087 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ] o.e.j.s.Server
Started @2684072ms
[junit4] 2> 2679087 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema15.xml,
solrconfig=solrconfig-plugcollector.xml, hostContext=/, hostPort=63517,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard2\cores}
[junit4] 2> 2679087 ERROR
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2679088 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 2679088 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 2679088 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2679088 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-27T20:07:20.333Z
[junit4] 2> 2679089 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard2\solr.xml
[junit4] 2> 2679103 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2679226 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard2\cores
[junit4] 2> 2679226 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2679228 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2679318 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2679337 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2679387 WARN (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema
is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2679388 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2679406 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from configset
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2679406 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard2\cores\collection1\data\]
[junit4] 2> 2679407 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@195b2c1
[junit4] 2> 2679408 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=19.1904296875,
floorSegmentMB=0.5712890625, forceMergeDeletesPctAllowed=27.22354795804939,
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4061384562511272
[junit4] 2> 2679557 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2679557 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2679558 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2679558 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2679559 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2679560 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@795542[collection1]
main]
[junit4] 2> 2679560 WARN (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf;
switching to use InMemory storage instead.
[junit4] 2> 2679561 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2679573 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2679614 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml
[junit4] 2> 2679627 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2679628 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2679628 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2679628 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2679628 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2679629 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2679629 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2679630 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2679630 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2679630 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2679632 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@795542[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2679682 INFO (coreLoadExecutor-11160-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1560518109091069952
[junit4] 2> 2680274 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2680329 INFO (qtp11127953-25040) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560518109759012864)} 0 10
[junit4] 2> 2680370 INFO (qtp21243792-25060) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560518109809344512)} 0 2
[junit4] 2> 2680418 INFO (qtp16143329-25080) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560518109858627584)} 0 3
[junit4] 2> 2680617 INFO (qtp25342864-25100) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560518109912104960)} 0 152
[junit4] 2> 2680621 INFO (qtp11127953-25040) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[1 (1560518110075682816)]} 0 0
[junit4] 2> 2680624 INFO (qtp21243792-25067) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[1 (1560518110078828544)]} 0 0
[junit4] 2> 2680627 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[2 (1560518110081974272)]} 0 0
[junit4] 2> 2680629 INFO (qtp21243792-25071) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[2 (1560518110084071424)]} 0 0
[junit4] 2> 2680630 INFO (qtp11127953-25045) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[5 (1560518110085120000)]} 0 0
[junit4] 2> 2680632 INFO (qtp16143329-25090) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[5 (1560518110087217152)]} 0 0
[junit4] 2> 2680635 INFO (qtp11127953-25044) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[6 (1560518110090362880)]} 0 0
[junit4] 2> 2680638 INFO (qtp16143329-25087) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[6 (1560518110093508608)]} 0 0
[junit4] 2> 2680641 INFO (qtp11127953-25043) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[7 (1560518110096654336)]} 0 0
[junit4] 2> 2680661 INFO (qtp21243792-25060) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[7 (1560518110115528704)]} 0 1
[junit4] 2> 2680669 INFO (qtp11127953-25043) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[8 (1560518110126014464)]} 0 0
[junit4] 2> 2680676 INFO (qtp16143329-25087) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[8 (1560518110129160192)]} 0 4
[junit4] 2> 2680679 INFO (qtp11127953-25052) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[9 (1560518110136500224)]} 0 0
[junit4] 2> 2680690 INFO (qtp25342864-25107) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[9 (1560518110138597376)]} 0 9
[junit4] 2> 2680693 INFO (qtp11127953-25047) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[10 (1560518110151180288)]} 0 0
[junit4] 2> 2680697 INFO (qtp25342864-25106) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[10 (1560518110155374592)]} 0 0
[junit4] 2> 2680739 INFO (qtp11127953-25047) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[11 (1560518110199414784)]} 0 0
[junit4] 2> 2680743 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[11 (1560518110202560512)]} 0 0
[junit4] 2> 2680745 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[12 (1560518110205706240)]} 0 0
[junit4] 2> 2680749 INFO (qtp16143329-25085) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[12 (1560518110209900544)]} 0 0
[junit4] 2> 2680751 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[13 (1560518110211997696)]} 0 0
[junit4] 2> 2680756 INFO (qtp16143329-25085) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update
params={wt=javabin&version=2}{add=[13 (1560518110217240576)]} 0 0
[junit4] 2> 2680759 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2680759 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1b7fe3a
[junit4] 2> 2680784 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b058d6[collection1] main]
[junit4] 2> 2680786 INFO
(searcherExecutor-11128-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1b058d6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C11)))}
[junit4] 2> 2680788 INFO (qtp11127953-25046) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2680788 INFO (qtp11127953-25046) [ 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 29
[junit4] 2> 2680790 INFO (qtp21243792-25060) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2680790 INFO (qtp21243792-25060) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@19bac45
[junit4] 2> 2680796 INFO (qtp21243792-25060) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@5a4bad[collection1] main]
[junit4] 2> 2680797 INFO (qtp21243792-25060) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2680799 INFO
(searcherExecutor-11139-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5a4bad[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C3)))}
[junit4] 2> 2680799 INFO (qtp21243792-25060) [ 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> 2680800 INFO (qtp16143329-25085) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2680800 INFO (qtp16143329-25085) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1675780
[junit4] 2> 2680804 INFO (qtp16143329-25085) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@d5a5cd[collection1] main]
[junit4] 2> 2680805 INFO (qtp16143329-25085) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2680806 INFO
(searcherExecutor-11150-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@d5a5cd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C5)))}
[junit4] 2> 2680806 INFO (qtp16143329-25085) [ 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> 2680809 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2680809 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@175db5b
[junit4] 2> 2680817 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1c03c44[collection1] main]
[junit4] 2> 2680817 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2680819 INFO
(searcherExecutor-11161-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1c03c44[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C3)))}
[junit4] 2> 2680819 INFO (qtp25342864-25105) [ 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 10
[junit4] 2> 2680830 INFO (qtp11127953-25045) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&sort=sort_i+asc&rows=12&wt=javabin&version=2&rq={!rank}}
hits=11 status=0 QTime=7
[junit4] 2> 2680840 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=https://127.0.0.1:63514//collection1|[ff01::114]:33332/&rows=12&version=2&q=*:*&NOW=1488226042077&isShard=true&wt=javabin&rq={!rank}}
hits=5 status=0 QTime=0
[junit4] 2> 2680846 INFO (qtp25342864-25114) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|https://127.0.0.1:63517//collection1|[ff01::213]:33332/&rows=12&version=2&q=*:*&NOW=1488226042077&isShard=true&wt=javabin&rq={!rank}}
hits=3 status=0 QTime=0
[junit4] 2> 2680847 INFO (qtp21243792-25067) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=https://127.0.0.1:63511//collection1&rows=12&version=2&q=*:*&NOW=1488226042077&isShard=true&wt=javabin&rq={!rank}}
hits=3 status=0 QTime=0
[junit4] 2> 2680850 INFO (qtp25342864-25114) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042077&ids=11,9,10&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|https://127.0.0.1:63517//collection1|[ff01::213]:33332/&rows=12&wt=javabin&version=2&rq={!rank}}
status=0 QTime=0
[junit4] 2> 2680851 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042077&ids=12,13,5,6,8&isShard=true&shard.url=https://127.0.0.1:63514//collection1|[ff01::114]:33332/&rows=12&wt=javabin&version=2&rq={!rank}}
status=0 QTime=0
[junit4] 2> 2680851 INFO (qtp21243792-25067) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042077&ids=1,2,7&isShard=true&shard.url=https://127.0.0.1:63511//collection1&rows=12&wt=javabin&version=2&rq={!rank}}
status=0 QTime=0
[junit4] 2> 2680852 INFO (qtp21243792-25064) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&shards=https://127.0.0.1:63511//collection1,https://127.0.0.1:63514//collection1|[ff01::114]:33332/,[ff01::083]:33332/|[ff01::114]:33332/|https://127.0.0.1:63517//collection1|[ff01::213]:33332/&fl=*,score&sort=sort_i+asc&rows=12&wt=javabin&version=2&rq={!rank}}
hits=11 status=0 QTime=20
[junit4] 2> 2680853 INFO (qtp11127953-25045) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&sort=sort_i+asc&rows=12&wt=javabin&version=2}
hits=11 status=0 QTime=0
[junit4] 2> 2680862 INFO (qtp25342864-25107) [ 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=sort_i+asc&shard.url=https://127.0.0.1:63517//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=12&version=2&q=*:*&NOW=1488226042100&isShard=true&wt=javabin}
hits=3 status=0 QTime=0
[junit4] 2> 2680864 INFO (qtp21243792-25067) [ 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=sort_i+asc&shard.url=[ff01::213]:33332/|https://127.0.0.1:63511//collection1&rows=12&version=2&q=*:*&NOW=1488226042100&isShard=true&wt=javabin}
hits=3 status=0 QTime=0
[junit4] 2> 2680866 INFO (qtp16143329-25083) [ 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=sort_i+asc&shard.url=[ff01::083]:33332/|https://127.0.0.1:63514//collection1&rows=12&version=2&q=*:*&NOW=1488226042100&isShard=true&wt=javabin}
hits=5 status=0 QTime=0
[junit4] 2> 2680867 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488226042100&ids=12,13,5,6,8&isShard=true&shard.url=[ff01::083]:33332/|https://127.0.0.1:63514//collection1&rows=12&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2680868 INFO (qtp25342864-25107) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488226042100&ids=11,9,10&isShard=true&shard.url=https://127.0.0.1:63517//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=12&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2680868 INFO (qtp21243792-25067) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488226042100&ids=1,2,7&isShard=true&shard.url=[ff01::213]:33332/|https://127.0.0.1:63511//collection1&rows=12&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2680869 INFO (qtp25342864-25114) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&shards=[ff01::213]:33332/|https://127.0.0.1:63511//collection1,[ff01::083]:33332/|https://127.0.0.1:63514//collection1,https://127.0.0.1:63517//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=sort_i+asc&rows=12&wt=javabin&version=2}
hits=11 status=0 QTime=13
[junit4] 2> 2680874 INFO (qtp11127953-25045) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&sort=sort_i+asc&rows=12&wt=javabin&version=2&rq={!rank+mergeStrategy%3D1}}
hits=11 status=0 QTime=2
[junit4] 2> 2680880 INFO (qtp25342864-25105) [ 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=sort_i+asc&shard.url=https://127.0.0.1:63517//collection1&rows=12&version=2&q=*:*&NOW=1488226042121&isShard=true&wt=javabin&rq={!rank+mergeStrategy%3D1}}
hits=3 status=0 QTime=2
[junit4] 2> 2680881 INFO (qtp21243792-25067) [ 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=sort_i+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:63511//collection1&rows=12&version=2&q=*:*&NOW=1488226042121&isShard=true&wt=javabin&rq={!rank+mergeStrategy%3D1}}
hits=3 status=0 QTime=1
[junit4] 2> 2680881 INFO (qtp16143329-25083) [ 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=sort_i+asc&shard.url=[ff01::213]:33332/|https://127.0.0.1:63514//collection1&rows=12&version=2&q=*:*&NOW=1488226042121&isShard=true&wt=javabin&rq={!rank+mergeStrategy%3D1}}
hits=5 status=0 QTime=1
[junit4] 2> 2680889 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488226042121&ids=12,13,5,6,8&isShard=true&shard.url=[ff01::213]:33332/|https://127.0.0.1:63514//collection1&rows=12&wt=javabin&version=2&rq={!rank+mergeStrategy%3D1}}
status=0 QTime=0
[junit4] 2> 2680890 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488226042121&ids=11,9,10&isShard=true&shard.url=https://127.0.0.1:63517//collection1&rows=12&wt=javabin&version=2&rq={!rank+mergeStrategy%3D1}}
status=0 QTime=0
[junit4] 2> 2680890 INFO (qtp21243792-25067) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488226042121&ids=1,2,7&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:63511//collection1&rows=12&wt=javabin&version=2&rq={!rank+mergeStrategy%3D1}}
status=0 QTime=0
[junit4] 2> 2680891 INFO (qtp25342864-25107) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:63511//collection1,[ff01::213]:33332/|https://127.0.0.1:63514//collection1,https://127.0.0.1:63517//collection1&sort=sort_i+asc&rows=12&wt=javabin&version=2&rq={!rank+mergeStrategy%3D1}}
hits=11 status=0 QTime=13
[junit4] 2> 2680893 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=[ff01::114]:33332/|https://127.0.0.1:63517//collection1|[ff01::083]:33332/&rows=12&version=2&q=*:*&NOW=1488226042137&isShard=true&wt=javabin&rq={!rank}}
hits=3 status=0 QTime=0
[junit4] 2> 2680894 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:63514//collection1&rows=12&version=2&q=*:*&NOW=1488226042137&isShard=true&wt=javabin&rq={!rank}}
hits=5 status=0 QTime=0
[junit4] 2> 2680894 INFO (qtp21243792-25071) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=https://127.0.0.1:63511//collection1&rows=12&version=2&q=*:*&NOW=1488226042137&isShard=true&wt=javabin&rq={!rank}}
hits=3 status=0 QTime=0
[junit4] 2> 2680896 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042137&ids=11,9,10&isShard=true&shard.url=[ff01::114]:33332/|https://127.0.0.1:63517//collection1|[ff01::083]:33332/&rows=12&wt=javabin&version=2&rq={!rank}}
status=0 QTime=0
[junit4] 2> 2680896 INFO (qtp21243792-25071) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042137&ids=1,2,7&isShard=true&shard.url=https://127.0.0.1:63511//collection1&rows=12&wt=javabin&version=2&rq={!rank}}
status=0 QTime=0
[junit4] 2> 2680897 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042137&ids=12,13,5,6,8&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:63514//collection1&rows=12&wt=javabin&version=2&rq={!rank}}
status=0 QTime=0
[junit4] 2> 2680897 INFO (qtp21243792-25067) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&shards=https://127.0.0.1:63511//collection1,[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:63514//collection1,[ff01::114]:33332/|https://127.0.0.1:63517//collection1|[ff01::083]:33332/&fl=*,score&sort=sort_i+asc&rows=12&wt=javabin&version=2&rq={!rank}}
hits=11 status=0 QTime=6
[junit4] 2> 2680925 INFO (qtp21243792-25071) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=[ff01::114]:33332/|https://127.0.0.1:63511//collection1&rows=12&version=2&q=*:*&NOW=1488226042145&isShard=true&wt=javabin}
hits=3 status=0 QTime=0
[junit4] 2> 2680929 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=[ff01::083]:33332/|https://127.0.0.1:63517//collection1&rows=12&version=2&q=*:*&NOW=1488226042145&isShard=true&wt=javabin}
hits=3 status=0 QTime=0
[junit4] 2> 2680935 INFO (qtp16143329-25092) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=sort_i+asc&shard.url=https://127.0.0.1:63514//collection1|[ff01::114]:33332/&rows=12&version=2&q=*:*&NOW=1488226042145&isShard=true&wt=javabin}
hits=5 status=0 QTime=0
[junit4] 2> 2680938 INFO (qtp21243792-25071) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042145&ids=1,2,7&isShard=true&shard.url=[ff01::114]:33332/|https://127.0.0.1:63511//collection1&rows=12&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2680938 INFO (qtp16143329-25092) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042145&ids=12,13,5,6,8&isShard=true&shard.url=https://127.0.0.1:63514//collection1|[ff01::114]:33332/&rows=12&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2680939 INFO (qtp25342864-25105) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488226042145&ids=11,9,10&isShard=true&shard.url=[ff01::083]:33332/|https://127.0.0.1:63517//collection1&rows=12&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2680939 INFO (qtp16143329-25083) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp= path=/select
params={q=*:*&shards=[ff01::114]:33332/|https://127.0.0.1:63511//collection1,https://127.0.0.1:63514//collection1|[ff01::114]:33332/,[ff01::083]:33332/|https://127.0.0.1:63517//collection1&fl=*,score&sort=sort_i+asc&rows=12&wt=javabin&version=2}
hits=11 status=0 QTime=39
[junit4] 2> 2680941 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 2680943 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@a45b6{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2680943 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=33013106
[junit4] 2> 2680943 INFO (coreCloseExecutor-11166-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@f888c8
[junit4] 2> 2681210 INFO (coreCloseExecutor-11166-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 2681210 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2681211 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@780594{/,null,UNAVAILABLE}
[junit4] 2> 2681215 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@1e740cb{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2681215 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=19069338
[junit4] 2> 2681217 INFO (coreCloseExecutor-11168-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@61288d
[junit4] 2> 2681510 INFO (coreCloseExecutor-11168-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 2681511 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2681511 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@793de9{/,null,UNAVAILABLE}
[junit4] 2> 2681514 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@73ccf6{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2681514 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=26857541
[junit4] 2> 2681515 INFO (coreCloseExecutor-11170-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@14e2cba
[junit4] 2> 2681606 INFO (coreCloseExecutor-11170-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 2681607 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2681607 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@e6981a{/,null,UNAVAILABLE}
[junit4] 2> 2681610 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@24dc5c{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2681610 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=5708700
[junit4] 2> 2681610 INFO (coreCloseExecutor-11172-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@8708fc
[junit4] 2> 2681657 INFO (coreCloseExecutor-11172-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 2681659 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2681659 INFO
(TEST-MergeStrategyTest.test-seed#[5F947E1F84CBBD7E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@ef506b{/,null,UNAVAILABLE}
[junit4] 2> 2681662 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2681662 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=6890522
[junit4] 2> 2681663 INFO (coreCloseExecutor-11174-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@13035cf
[junit4] 2> 2681682 INFO (coreCloseExecutor-11174-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 2681685 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2681686 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for all SolrIndexSearchers to be released
[junit4] 2> 2681686 INFO
(SUITE-MergeStrategyTest-seed#[5F947E1F84CBBD7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=ar-SD,
timezone=Asia/Karachi
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_121
(32-bit)/cpus=3,threads=1,free=197206272,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [BasicAuthIntegrationTest,
TestSolrQueryParser, DistributedFacetExistsSmallTest,
TestManagedSynonymFilterFactory, TestReversedWildcardFilterFactory,
TestMiniSolrCloudClusterSSL, SliceStateTest, SmileWriterTest,
JsonValidatorTest, SearchHandlerTest, PeerSyncReplicationTest,
CdcrReplicationDistributedZkTest, SpellCheckCollatorTest,
TestClusterProperties, TestSolrQueryParserDefaultOperatorResource,
TestSolrFieldCacheMBean, NotRequiredUniqueKeyTest, TestPointFields,
TestUnifiedSolrHighlighter, SuggesterWFSTTest, WrapperMergePolicyFactoryTest,
SimpleMLTQParserTest, DistributedTermsComponentTest,
TestCollationFieldDocValues, SuggesterTest, TestTestInjection,
TestAtomicUpdateErrorCases, TestMiniSolrCloudCluster,
ParsingFieldUpdateProcessorsTest, TestSQLHandler, TestMacroExpander,
JavabinLoaderTest, LeaderFailoverAfterPartitionTest, BooleanFieldTest,
TestRebalanceLeaders, TestFieldCacheSortRandom, TestMergePolicyConfig,
MinimalSchemaTest, TestQuerySenderListener, JsonLoaderTest,
TestOverriddenPrefixQueryForCustomFieldType, ShowFileRequestHandlerTest,
FieldAnalysisRequestHandlerTest, TestManagedSchemaAPI, TestRealTimeGet,
TestBackupRepositoryFactory, CircularListTest, TestLuceneMatchVersion,
TestFreeTextSuggestions, SharedFSAutoReplicaFailoverTest, TestRawTransformer,
ConvertedLegacyTest, ConjunctionSolrSpellCheckerTest,
TestSolrCloudWithSecureImpersonation, DistributedFacetPivotLongTailTest,
TestRandomFaceting, CSVRequestHandlerTest, TestTolerantUpdateProcessorCloud,
BadIndexSchemaTest, FullHLLTest, DistributedFacetPivotSmallTest,
SolrCloudExampleTest, TestSolrDeletionPolicy2, ConnectionManagerTest,
TestReRankQParserPlugin, TestFuzzyAnalyzedSuggestions,
SegmentsInfoRequestHandlerTest, TestUninvertingReader, LoggingHandlerTest,
SpellPossibilityIteratorTest, SolrIndexSplitterTest, DataDrivenBlockJoinTest,
FileBasedSpellCheckerTest, TestMinMaxOnMultiValuedField,
FullSolrCloudDistribCmdsTest, TestFastWriter, InfixSuggestersTest,
PreAnalyzedFieldTest, TestDistribIDF, TestJsonFacetRefinement,
RemoteQueryErrorTest, TestPHPSerializedResponseWriter, TestCloudInspectUtil,
TestJettySolrRunner, SolrCoreCheckLockOnStartupTest,
DocExpirationUpdateProcessorFactoryTest, TestSystemIdResolver,
TestFieldResource, TestWriterPerf, DistributedSuggestComponentTest,
AnalysisErrorHandlingTest, BasicDistributedZk2Test, TestDistributedMissingSort,
TestHdfsCloudBackupRestore, TlogReplayBufferedWhileIndexingTest,
TestQuerySenderNoQuery, TestUniqueKeyFieldResource, TestSchemaResource,
TestChildDocTransformer, TestIndexingPerformance,
TermVectorComponentDistributedTest, TestInitParams, TestStressUserVersions,
DateRangeFieldTest, TestConfig, BlockCacheTest,
ClassificationUpdateProcessorTest, CollectionsAPIDistributedZkTest,
BufferStoreTest, RequestHandlersTest, RuleEngineTest, RegexBoostProcessorTest,
TestReloadDeadlock, TestCustomStream, UUIDFieldTest, TestPushWriter,
TestReqParamsAPI, MigrateRouteKeyTest, SolrIndexMetricsTest,
HdfsNNFailoverTest, TestMissingGroups, LeaderElectionIntegrationTest,
ConcurrentDeleteAndCreateCollectionTest, TestGraphTermsQParserPlugin,
TestSearchPerf, MetricUtilsTest, TestShortCircuitedRequests,
SpellingQueryConverterTest, BitVectorTest, TestDefaultSearchFieldResource,
TestExactStatsCache, HdfsLockFactoryTest, TestSolrXml,
SimpleCollectionCreateDeleteTest, HdfsSyncSliceTest,
BinaryUpdateRequestHandlerTest, SolrCoreMetricManagerTest, TestPathTrie,
TestBinaryResponseWriter, TestDistributedStatsComponentCardinality,
TestSSLRandomization, TestLMDirichletSimilarityFactory,
RAMDirectoryFactoryTest, TestRTimerTree, DeleteLastCustomShardedReplicaTest,
ZkStateWriterTest, LukeRequestHandlerTest, TestQueryTypes, TestIndexSearcher,
ShardRoutingCustomTest, TestQueryUtils, SuggestComponentContextFilterQueryTest,
HttpPartitionTest, TestRequestForwarding, SolrJmxReporterTest,
DateMathParserTest, HdfsTlogReplayBufferedWhileIndexingTest,
OverriddenZkACLAndCredentialsProvidersTest, UpdateRequestProcessorFactoryTest,
ChaosMonkeySafeLeaderTest, MBeansHandlerTest, TestSystemCollAutoCreate,
TestDynamicFieldResource, HdfsRecoveryZkTest, TestDownShardTolerantSearch,
TestFieldCacheWithThreads, LeaderInitiatedRecoveryOnCommitTest,
UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, BasicZkTest,
RecoveryZkTest, TestReplicationHandler, ClusterStateUpdateTest,
LeaderElectionTest, ZkSolrClientTest, TestZkChroot, ZkCLITest, AutoCommitTest,
TestDistributedGrouping, TestFaceting, TestRecovery, ZkControllerTest,
TestJoin, TestStressVersions, TestRangeQuery, SpellCheckComponentTest,
TestGroupingSearch, SolrCmdDistributorTest, PeerSyncTest, TestBadConfig,
TestCoreDiscovery, TestExtendedDismaxParser, CoreAdminHandlerTest,
SuggesterTSTTest, TestTrie, SpatialFilterTest, NoCacheHeaderTest,
WordBreakSolrSpellCheckerTest, TestUpdate, FieldMutatingUpdateProcessorTest,
QueryEqualityTest, DefaultValueUpdateProcessorTest, TestRemoteStreaming,
DistanceFunctionTest, XsltUpdateRequestHandlerTest, DebugComponentTest,
TestSurroundQueryParser, DirectSolrSpellCheckerTest, TestOmitPositions,
DocumentBuilderTest, TermVectorComponentTest, RequiredFieldsTest,
IndexSchemaTest, ReturnFieldsTest, QueryParsingTest, HighlighterConfigTest,
UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest,
ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, SampleTest,
TestDocSet, ExternalFileFieldSortTest, TestSolrCoreProperties,
DirectSolrConnectionTest, TestCharFilters, TestCodecSupport,
TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory,
TestBM25SimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest,
ScriptEngineTest, TestFastLRUCache, TestSuggestSpellingConverter,
DateFieldTest, ClusterStateTest, TestUtils, TestDocumentBuilder,
ZkNodePropsTest, SystemInfoHandlerTest, CursorPagingTest,
DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCrossCoreJoin,
TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping,
TestSimpleTrackingShardHandler, AssignTest, BaseCdcrDistributedZkTest,
CdcrRequestHandlerTest, CloudExitableDirectoryReaderTest, CollectionReloadTest,
CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest,
DeleteShardTest, DeleteStatusTest, DistributedQueueTest,
DistributedVersionInfoTest, LeaderInitiatedRecoveryOnShardRestartTest,
MultiThreadedOCPTest, SaslZkACLProviderTest, TestCloudPivotFacet,
TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestCryptoKeys,
TestLocalFSCloudBackupRestore, TestRandomRequestDistribution,
TestSizeLimitedDistributedMap, TestStressCloudBlindAtomicUpdates,
TestStressInPlaceUpdates, TestImplicitCoreProperties, TestInfoStreamLogging,
TestReloadAndDeleteDocs, TestSolrConfigHandler, TestSolrDynamicMBean,
TestSolrCloudSnapshots, TestConfigReload, TestSolrConfigHandlerCloud,
SuggestComponentTest, TestExpandComponent, TestPivotHelperCode,
TestTrackingShardHandlerFactory, SolrSlf4jReporterTest,
TestSubQueryTransformerCrossCore, TestManagedResource, TestRestManager,
TestBulkSchemaAPI, BadCopyFieldTest, ChangedSchemaMergeTest,
DocValuesMissingTest, TestBulkSchemaConcurrent, TestSchemaManager,
TestSchemalessBufferedUpdates, TestUseDocValuesAsStored2, AnalyticsQueryTest,
CursorMarkTest, MergeStrategyTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MergeStrategyTest
-Dtests.seed=5F947E1F84CBBD7E -Dtests.slow=true -Dtests.locale=ar-SD
-Dtests.timezone=Asia/Karachi -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | MergeStrategyTest (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the
following files (in the order of attempts):
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\collection1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1\collection1
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001\shard1
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.MergeStrategyTest_5F947E1F84CBBD7E-001\tempDir-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([5F947E1F84CBBD7E]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [626/696 (1!)] on J1 in 9.98s, 1 test, 1 error <<<
FAILURES!
[...truncated 55143 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
