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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org