You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/08/31 04:10:58 UTC
[JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_102) - Build #
425 - Unstable!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/425/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([A2A5B4109A416C55:55D65A485CA9C3B3]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1331)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestSolrConfigHandlerCloud
Error Message:
ObjectTracker found 3 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper]
at __randomizedtesting.SeedInfo.seed([A2A5B4109A416C55]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:258)
at sun.reflect.GeneratedMethodAccessor42.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10902 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\init-core-data-001
[junit4] 2> 412803 INFO (SUITE-TestReplicationHandler-seed#[A2A5B4109A416C55]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 412805 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
[junit4] 2> 412805 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1
[junit4] 2> 412819 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 412821 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c540111{/solr,null,AVAILABLE}
[junit4] 2> 412823 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5b05644b{HTTP/1.1,[http/1.1]}{127.0.0.1:55740}
[junit4] 2> 412823 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.Server Started @417378ms
[junit4] 2> 412823 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=55740}
[junit4] 2> 412823 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 412823 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001'
[junit4] 2> 412825 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 412825 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 412825 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\solr.xml
[junit4] 2> 412870 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.
[junit4] 2> 412870 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer New CoreContainer 1262885889
[junit4] 2> 412870 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001]
[junit4] 2> 412870 WARN (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\lib
[junit4] 2> 412870 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 412875 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 412876 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 412876 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 412876 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 412876 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 412878 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.
[junit4] 2> 412882 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\}
[junit4] 2> 412882 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1
[junit4] 2> 412883 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 412884 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 412884 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 412972 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1'
[junit4] 2> 412979 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 412979 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 413002 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 413005 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 413012 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 413017 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 413024 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 413032 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1'
[junit4] 2> 413032 INFO (coreLoadExecutor-958-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1
[junit4] 2> 413032 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 413033 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 413033 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 413033 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\snapshot_metadata
[junit4] 2> 413033 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 413034 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\
[junit4] 2> 413034 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\index/
[junit4] 2> 413034 WARN (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 413035 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\index
[junit4] 2> 413035 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=96.5322265625, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=5.678369291266135, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 413036 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_1,generation=1}
[junit4] 2> 413036 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 413037 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 413045 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413046 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413047 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413050 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413053 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 413053 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 413054 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 413054 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 413054 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 413055 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5575401515863723]
[junit4] 2> 413056 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_1,generation=1}
[junit4] 2> 413056 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 413056 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13359b2e[collection1] main]
[junit4] 2> 413056 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1\conf
[junit4] 2> 413056 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1\conf}
[junit4] 2> 413056 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\collection1\conf
[junit4] 2> 413057 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 413057 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 413057 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 413057 INFO (coreLoadExecutor-958-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 413058 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-001\.\collection1\data\
[junit4] 2> 413058 INFO (searcherExecutor-959-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13359b2e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 413398 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1
[junit4] 2> 413411 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 413411 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@55e2a6fe{/solr,null,AVAILABLE}
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.ServerConnector Started ServerConnector@cf58c8b{HTTP/1.1,[http/1.1]}{127.0.0.1:55743}
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.e.j.s.Server Started @417968ms
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=55743}
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002'
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 413413 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 413414 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\solr.xml
[junit4] 2> 413419 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.
[junit4] 2> 413419 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer New CoreContainer 1621389864
[junit4] 2> 413419 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002]
[junit4] 2> 413419 WARN (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\lib
[junit4] 2> 413431 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 413433 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 413433 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 413433 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 413433 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 413433 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 413434 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.
[junit4] 2> 413444 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\}
[junit4] 2> 413444 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1
[junit4] 2> 413444 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 413445 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 413445 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 413448 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1'
[junit4] 2> 413449 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 413449 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 413458 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 413465 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 413476 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 413479 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 413481 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 413483 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1'
[junit4] 2> 413483 INFO (coreLoadExecutor-968-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1
[junit4] 2> 413484 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 413484 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 413484 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 413484 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\snapshot_metadata
[junit4] 2> 413484 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 413485 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\
[junit4] 2> 413485 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index/
[junit4] 2> 413485 WARN (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 413485 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index
[junit4] 2> 413485 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=96.5322265625, floorSegmentMB=1.0966796875, forceMergeDeletesPctAllowed=5.678369291266135, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 413486 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_1,generation=1}
[junit4] 2> 413486 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 413487 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\
[junit4] 2> 413488 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 413490 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413491 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413492 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413493 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 413495 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 413495 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 413495 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 413495 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 413495 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 413496 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5575401515863723]
[junit4] 2> 413496 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_1,generation=1}
[junit4] 2> 413496 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 413496 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e33fe45[collection1] main]
[junit4] 2> 413497 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1\conf
[junit4] 2> 413497 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1\conf}
[junit4] 2> 413497 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\collection1\conf
[junit4] 2> 413502 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 413504 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 413504 INFO (searcherExecutor-969-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e33fe45[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 413505 INFO (coreLoadExecutor-968-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 413613 INFO (qtp274928615-2124) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 413953 INFO (qtp274928615-2123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 413958 INFO (qtp274928615-2121) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 413960 INFO (qtp274928615-2120) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 413962 INFO (qtp274928615-2120) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_2,generation=2}
[junit4] 2> 413963 INFO (qtp274928615-2120) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 413967 INFO (qtp274928615-2120) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3178adfe[collection1] main]
[junit4] 2> 413967 INFO (qtp274928615-2120) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 413967 INFO (searcherExecutor-959-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3178adfe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))}
[junit4] 2> 413967 INFO (qtp274928615-2120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 413990 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 413995 INFO (qtp274928615-2136) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 413995 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 413995 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 413995 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 414008 INFO (qtp274928615-2135) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=10
[junit4] 2> 414008 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 8
[junit4] 2> 414009 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044955962
[junit4] 2> 414010 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 414013 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=633750816, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 414014 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_1,generation=1}
[junit4] 2> 414014 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 414014 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 414014 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_1,generation=1}
[junit4] 2> 414014 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 414015 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 414016 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1e1c26b2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@48447264)
[junit4] 2> 414018 INFO (qtp274928615-2137) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414025 INFO (qtp274928615-2127) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414028 INFO (qtp274928615-2124) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414030 INFO (qtp274928615-2122) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Memory_0.ram&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414032 INFO (qtp274928615-2123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414034 INFO (qtp274928615-2121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414038 INFO (qtp274928615-2120) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414040 INFO (qtp274928615-2136) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414040 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1261) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1e1c26b2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@48447264)
[junit4] 2> 414044 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=93.8193359375, floorSegmentMB=0.91015625, forceMergeDeletesPctAllowed=28.549229554267544, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 414045 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> 414045 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 414046 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 414048 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@26e72747[collection1] main]
[junit4] 2> 414048 INFO (searcherExecutor-969-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26e72747[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))}
[junit4] 2> 414049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044955962 [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044955962;done=true>>]
[junit4] 2> 414049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=false
[junit4] 2> 414049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044955962
[junit4] 2> 414049 INFO (qtp578518156-2147) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:55740/solr/collection1/replication&command=fetchindex} status=0 QTime=63
[junit4] 2> 414058 INFO (qtp578518156-2142) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 414059 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 414061 INFO (qtp274928615-2135) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 414069 INFO (qtp274928615-2137) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=5
[junit4] 2> 414076 INFO (qtp274928615-2127) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 414076 INFO (qtp578518156-2141) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
[junit4] 2> 414078 INFO (qtp274928615-2124) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414079 INFO (qtp578518156-2140) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414080 INFO (qtp274928615-2122) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 414080 INFO (qtp274928615-2122) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 414080 INFO (qtp274928615-2122) [ x:collection1] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 414080 INFO (qtp274928615-2122) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 414080 INFO (qtp274928615-2122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 414086 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 414089 INFO (qtp274928615-2123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414089 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 414089 INFO (qtp578518156-2153) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:55740/solr/collection1/replication&command=fetchindex} status=0 QTime=8
[junit4] 2> 414093 INFO (qtp274928615-2121) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 414094 INFO (qtp274928615-2120) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 414100 INFO (qtp274928615-2120) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_3,generation=3}
[junit4] 2> 414100 INFO (qtp274928615-2120) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 414101 INFO (qtp274928615-2120) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@46fcdcd7[collection1] main]
[junit4] 2> 414101 INFO (qtp274928615-2120) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 414101 INFO (searcherExecutor-959-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46fcdcd7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1) Uninverting(_1(6.3.0):C1)))}
[junit4] 2> 414101 INFO (qtp274928615-2120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 414102 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 414124 INFO (qtp274928615-2136) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414124 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 414124 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 414124 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 414127 INFO (qtp274928615-2136) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2
[junit4] 2> 414127 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 15
[junit4] 2> 414127 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044956080
[junit4] 2> 414130 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 414132 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=633750816, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 414135 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> 414135 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 414136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 414136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> 414136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 414138 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 414144 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@6fc072d3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@127d79d0)
[junit4] 2> 414145 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 414146 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 414146 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 414146 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Memory_0.ram because it already exists
[junit4] 2> 414146 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 414146 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 414148 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 414151 INFO (qtp274928615-2137) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414153 INFO (qtp274928615-2127) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414155 INFO (qtp274928615-2124) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Memory_0.ram&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414159 INFO (qtp274928615-2122) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414162 INFO (qtp274928615-2123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414165 INFO (qtp274928615-2121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414168 INFO (qtp274928615-2120) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414175 INFO (qtp274928615-2135) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 414176 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1324) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@6fc072d3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@127d79d0)
[junit4] 2> 414180 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:681)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:493)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:254)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 414181 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=93.8193359375, floorSegmentMB=0.91015625, forceMergeDeletesPctAllowed=28.549229554267544, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 414184 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 414184 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 414185 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 414188 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3000f359[collection1] main]
[junit4] 2> 414188 INFO (searcherExecutor-969-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3000f359[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1) Uninverting(_1(6.3.0):C1)))}
[junit4] 2> 414188 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044956080 [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044956080;done=true>>]
[junit4] 2> 414189 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=false
[junit4] 2> 414189 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044956080
[junit4] 2> 414189 INFO (qtp578518156-2152) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:55740/solr/collection1/replication&command=fetchindex} status=0 QTime=87
[junit4] 2> 414192 INFO (qtp578518156-2157) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 414193 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[A2A5B4109A416C55]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 414199 INFO (qtp274928615-2136) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
[junit4] 2> 414220 INFO (qtp274928615-2137) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=18
[junit4] 2> 414232 INFO (qtp274928615-2127) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
[junit4] 2> 414233 INFO (qtp578518156-2144) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=12
[junit4] 2> 414235 INFO (qtp274928615-2124) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414236 INFO (qtp578518156-2147) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414239 INFO (qtp274928615-2122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 414241 INFO (qtp274928615-2123) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 414254 INFO (qtp274928615-2123) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=4
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d3f4867 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e6a430c),segFN=segments_4,generation=4}
[junit4] 2> 414255 INFO (qtp274928615-2123) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 4
[junit4] 2> 414256 INFO (qtp274928615-2123) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5dfcc388[collection1] realtime]
[junit4] 2> 414256 INFO (qtp274928615-2123) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 414256 INFO (qtp274928615-2123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 15
[junit4] 2> 414258 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 414265 INFO (qtp274928615-2121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 414265 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 414265 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 414265 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 414273 INFO (qtp274928615-2121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=6
[junit4] 2> 414273 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 22
[junit4] 2> 414274 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_A2A5B4109A416C55-001\solr-instance-002\.\collection1\data\index.20160831044956226
[junit4] 2> 414275 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 414278 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=633750816, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 414282 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 414284 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 414284 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 414284 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 414284 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 414285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 414285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 414285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 414285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 415285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 415285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 415285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 415285 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 416288 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 416288 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 416288 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 416288 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 417289 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 417289 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 417289 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 417289 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 418290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 418290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 418290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 418290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 419290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 419290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 419290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 419290 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 420291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 420291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 420291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 420291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 421291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 421291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 421291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 421291 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 422292 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 422292 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 422292 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 422292 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 423293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 423293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 423293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 423293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 424293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 424293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@308fecb3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a181c61),segFN=segments_3,generation=3}
[junit4] 2> 424293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 424293 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 425294 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able
[junit4] 2> 425294 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 425294 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@4b802ddf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20a2f749)
[junit4] 2> 425294 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 425294 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 425294 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Memory_0.ram because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Memory_0.ram because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
[junit4] 2> 425295 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
[junit4] 2> 425295 INFO (explicit
[...truncated too long message...]
ction1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\index
[junit4] 2> 2358929 INFO (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\;done=false>>]
[junit4] 2> 2358929 INFO (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2358929 INFO (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\
[junit4] 2> 2358930 INFO (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\snapshot_metadata [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\snapshot_metadata;done=false>>]
[junit4] 2> 2358930 INFO (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2358930 INFO (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestSolrConfigHandlerCloud_A2A5B4109A416C55-001\shard-4-001\cores\collection1\data\snapshot_metadata
[junit4] 2> 2358930 WARN (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCores Tried to remove core collection1 from pendingCoreOps and it wasn't there.
[junit4] 2> 2358930 WARN (Thread-2175) [n:127.0.0.1:49226_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: Unable to reload core [collection1]
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:946)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2578)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$3(ZkController.java:2443)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Failed to load config name for collection:collection1 due to:
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:815)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:503)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:940)
[junit4] 2> ... 3 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Failed to load config name for collection:collection1 due to:
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:98)
[junit4] 2> at org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2358)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:791)
[junit4] 2> ... 5 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading config name for collection collection1
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:197)
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:95)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:348)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:345)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:345)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:178)
[junit4] 2> ... 8 more
[junit4] 2> NOTE: test params are: codec=Lucene62, sim=ClassicSimilarity, locale=ru, timezone=Indian/Antananarivo
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=110258432,total=315719680
[junit4] 2> NOTE: All tests run in this JVM: [OpenExchangeRatesOrgProviderTest, CloneFieldUpdateProcessorFactoryTest, TestDistributedGrouping, TestConfigSets, TestDistribDocBasedVersion, TestRandomFaceting, TestFreeTextSuggestions, TestDefaultSearchFieldResource, TestExceedMaxTermLength, CachingDirectoryFactoryTest, SignatureUpdateProcessorFactoryTest, PluginInfoTest, TestFieldCache, TestRandomRequestDistribution, SparseHLLTest, TestTestInjection, TestSolrQueryParser, CollectionStateFormat2Test, TestChildDocTransformer, TestDistributedStatsComponentCardinality, GraphQueryTest, ExplicitHLLTest, TestFuzzyAnalyzedSuggestions, SuggestComponentContextFilterQueryTest, ZkSolrClientTest, TestFoldingMultitermQuery, NumericFieldsTest, PreAnalyzedUpdateProcessorTest, BadIndexSchemaTest, TestPerFieldSimilarityWithDefaultOverride, TestIndexingPerformance, TestReplicationHandlerBackup, TestConfigSetsAPIZkFailure, TestManagedSchemaAPI, TestPKIAuthenticationPlugin, TestScoreJoinQPScore, HLLSerializationTest, CacheHeaderTest, TestQueryUtils, TestCollapseQParserPlugin, SolrInfoMBeanTest, FileUtilsTest, PeerSyncReplicationTest, TestClassicSimilarityFactory, TestTolerantUpdateProcessorCloud, TestMaxScoreQueryParser, CurrencyFieldOpenExchangeTest, EchoParamsTest, TestRangeQuery, AsyncCallRequestStatusResponseTest, DistanceUnitsTest, CoreAdminHandlerTest, TestJoin, TestAuthenticationFramework, TestRTimerTree, DefaultValueUpdateProcessorTest, HdfsLockFactoryTest, BooleanFieldTest, WordBreakSolrSpellCheckerTest, DistributedIntervalFacetingTest, RollingRestartTest, DistributedQueryComponentOptimizationTest, MultiThreadedOCPTest, OverseerStatusTest, LukeRequestHandlerTest, TestRequestStatusCollectionAPI, SortByFunctionTest, TestBinaryField, ShowFileRequestHandlerTest, DistributedTermsComponentTest, CheckHdfsIndexTest, TestBinaryResponseWriter, CloudExitableDirectoryReaderTest, TolerantUpdateProcessorTest, RankQueryTest, SpellCheckCollatorTest, SpellingQueryConverterTest, SuggesterWFSTTest, DateRangeFieldTest, TestTrie, FieldMutatingUpdateProcessorTest, TlogReplayBufferedWhileIndexingTest, SpellPossibilityIteratorTest, TestBulkSchemaConcurrent, CustomCollectionTest, RuleEngineTest, TestHashQParserPlugin, RequestLoggingTest, TestBM25SimilarityFactory, IndexSchemaRuntimeFieldTest, OverseerModifyCollectionTest, TestSchemaResource, TestSolrCloudWithDelegationTokens, DateFieldTest, TestZkChroot, ConcurrentDeleteAndCreateCollectionTest, TestOnReconnectListenerSupport, TestSolrQueryResponse, CollectionsAPIAsyncDistributedZkTest, VMParamsZkACLAndCredentialsProvidersTest, TestClusterStateMutator, DateMathParserTest, TestCloudInspectUtil, TestSolrJ, StatsComponentTest, ConjunctionSolrSpellCheckerTest, TestSolrXml, TestJettySolrRunner, ConnectionReuseTest, TestRTGBase, TestFilteredDocIdSet, DistributedFacetPivotSmallAdvancedTest, TestDistributedMissingSort, TestFieldCacheSortRandom, TestReplicaProperties, AnalyticsMergeStrategyTest, AnalysisErrorHandlingTest, TestCoreDiscovery, CoreMergeIndexesAdminHandlerTest, DirectSolrConnectionTest, BasicDistributedZk2Test, TestIntervalFaceting, BigEndianAscendingWordSerializerTest, MultiTermTest, SolrCoreCheckLockOnStartupTest, VersionInfoTest, PKIAuthenticationIntegrationTest, SearchHandlerTest, TestExclusionRuleCollectionAccess, TestNoOpRegenerator, TestPivotHelperCode, TestImplicitCoreProperties, TestSolrConfigHandlerCloud]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrConfigHandlerCloud -Dtests.seed=A2A5B4109A416C55 -Dtests.slow=true -Dtests.locale=ru -Dtests.timezone=Indian/Antananarivo -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.00s J1 | TestSolrConfigHandlerCloud (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper]
[junit4] > at __randomizedtesting.SeedInfo.seed([A2A5B4109A416C55]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:258)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [299/631 (2!)] on J1 in 54.82s, 1 test, 1 failure <<< FAILURES!
[...truncated 61291 lines...]
[JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_102) - Build #
426 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/426/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
ObjectTracker found 4 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper, SolrCore]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper, SolrCore]
at __randomizedtesting.SeedInfo.seed([5F5C56A304A40F7A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:258)
at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11486 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\init-core-data-001
[junit4] 2> 1324794 INFO (SUITE-TestSolrCLIRunExample-seed#[5F5C56A304A40F7A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1324795 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSchemalessExample
[junit4] 2> 1324796 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.u.TestSolrCLIRunExample Selected port 50087 to start schemaless example Solr instance on ...
[junit4] 2> 1325873 INFO (Thread-2019) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1325874 INFO (Thread-2019) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cbbf7c4{/solr,null,AVAILABLE}
[junit4] 2> 1325879 INFO (Thread-2019) [ ] o.e.j.s.ServerConnector Started ServerConnector@282334e2{HTTP/1.1,[http/1.1]}{127.0.0.1:50087}
[junit4] 2> 1325879 INFO (Thread-2019) [ ] o.e.j.s.Server Started @1330810ms
[junit4] 2> 1325879 INFO (Thread-2019) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50087}
[junit4] 2> 1325879 INFO (Thread-2019) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1325879 INFO (Thread-2019) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr'
[junit4] 2> 1325879 INFO (Thread-2019) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1325880 INFO (Thread-2019) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1325880 INFO (Thread-2019) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\solr.xml
[junit4] 2> 1325889 INFO (Thread-2019) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr
[junit4] 2> 1325889 INFO (Thread-2019) [ ] o.a.s.c.CoreContainer New CoreContainer 1279855128
[junit4] 2> 1325889 INFO (Thread-2019) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr]
[junit4] 2> 1325889 WARN (Thread-2019) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\lib
[junit4] 2> 1325889 INFO (Thread-2019) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1325894 INFO (Thread-2019) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1325895 INFO (Thread-2019) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1325895 INFO (Thread-2019) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1325895 INFO (Thread-2019) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1325895 INFO (Thread-2019) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1325896 INFO (Thread-2019) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr
[junit4] 2> 1325896 INFO (Thread-2019) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1325897 INFO (Thread-2019) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1325897 INFO (Thread-2019) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1329028 INFO (qtp713833770-9192) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=142
[junit4] 2> 1329034 INFO (qtp713833770-9195) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 1329052 INFO (qtp713833770-9190) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=7
[junit4] 2> 1329062 INFO (qtp713833770-9192) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=5
[junit4] 2> 1329066 INFO (qtp713833770-9195) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 1329143 INFO (qtp713833770-9190) [ ] o.a.s.h.a.CoreAdminOperation core create command name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json
[junit4] 2> 1329144 INFO (qtp713833770-9190) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=gettingstarted, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\}
[junit4] 2> 1329144 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted'
[junit4] 2> 1329144 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1329144 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1329168 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1329169 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1329173 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\extraction\lib
[junit4] 2> 1329174 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist
[junit4] 2> 1329175 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\clustering\lib
[junit4] 2> 1329175 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist
[junit4] 2> 1329176 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\langid\lib
[junit4] 2> 1329176 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist
[junit4] 2> 1329177 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\contrib\velocity\lib
[junit4] 2> 1329177 WARN (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\..\..\..\..\dist
[junit4] 2> 1329184 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1329205 INFO (qtp713833770-9190) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1329220 INFO (qtp713833770-9190) [ ] o.a.s.s.IndexSchema [gettingstarted] Schema name=example-data-driven-schema
[junit4] 2> 1329417 INFO (qtp713833770-9190) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1329524 INFO (qtp713833770-9190) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1329531 INFO (qtp713833770-9190) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1329558 INFO (qtp713833770-9190) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted'
[junit4] 2> 1329558 INFO (qtp713833770-9190) [ ] o.a.s.c.CoreContainer Creating SolrCore 'gettingstarted' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted
[junit4] 2> 1329558 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1329558 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\]
[junit4] 2> 1329559 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@74a850a4
[junit4] 2> 1329559 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Added SolrEventListener for newSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 1329559 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Added SolrEventListener for firstSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 1329560 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\snapshot_metadata
[junit4] 2> 1329560 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 1329560 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SchemaCodecFactory Using default compressionMode: BEST_SPEED
[junit4] 2> 1329560 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\
[junit4] 2> 1329560 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\index/
[junit4] 2> 1329560 WARN (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\index' doesn't exist. Creating new index...
[junit4] 2> 1329561 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\index
[junit4] 2> 1329561 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3682f2fe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@96db32f),segFN=segments_1,generation=1}
[junit4] 2> 1329561 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1329562 INFO (OldIndexDirectoryCleanupThreadForCore-gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore Looking for old index directories to cleanup for core gettingstarted in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\
[junit4] 2> 1329562 WARN (OldIndexDirectoryCleanupThreadForCore-gettingstarted) [ x:gettingstarted] o.a.s.c.DirectoryFactory C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1329594 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag queryResponseWriter : 'velocity' created with startup=lazy
[junit4] 2> 1329635 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1329867 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 1330042 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1330049 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1330050 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1330051 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1330052 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1330056 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/terms' created with startup=lazy
[junit4] 2> 1330056 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/update/extract' created with startup=lazy
[junit4] 2> 1330056 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/analysis/field' created with startup=lazy
[junit4] 2> 1330056 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/analysis/document' created with startup=lazy
[junit4] 2> 1330057 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/spell' created with startup=lazy
[junit4] 2> 1330057 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/tvrh' created with startup=lazy
[junit4] 2> 1330057 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/elevate' created with startup=lazy
[junit4] 2> 1330057 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/sql,/graph,/tvrh,/select,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms,/debug/dump,/update/extract
[junit4] 2> 1330057 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1330058 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1330058 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1330061 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 1330061 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1330061 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3682f2fe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@96db32f),segFN=segments_1,generation=1}
[junit4] 2> 1330061 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1330061 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.s.SolrIndexSearcher Opening [Searcher@4acc1d43[gettingstarted] main]
[junit4] 2> 1330062 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\conf
[junit4] 2> 1330062 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\conf}
[junit4] 2> 1330062 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\conf
[junit4] 2> 1330062 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1330062 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1330063 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1330063 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent No queryConverter defined, using default converter
[junit4] 2> 1330064 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\conf\elevate.xml
[junit4] 2> 1330115 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1330116 INFO (searcherExecutor-4086-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@4acc1d43[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1330116 INFO (searcherExecutor-4086-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1330116 INFO (searcherExecutor-4086-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1330116 INFO (searcherExecutor-4086-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Registered new searcher Searcher@4acc1d43[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1330116 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1330117 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1330117 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1544186590546362368
[junit4] 2> 1330118 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1544186590546362368
[junit4] 2> 1330119 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.c.CoreContainer registering core: gettingstarted
[junit4] 2> 1330121 INFO (qtp713833770-9190) [ x:gettingstarted] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json} status=0 QTime=977
[junit4] 2> 1330131 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@282334e2{HTTP/1.1,[http/1.1]}{127.0.0.1:50087}
[junit4] 2> 1330131 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1279855128
[junit4] 2> 1330133 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] CLOSING SolrCore org.apache.solr.core.SolrCore@1e3216d8
[junit4] 2> 1330133 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommit maxTime=15000ms,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 1330133 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1330133 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1330133 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1330135 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Closing main searcher on request.
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\ [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\;done=false>>]
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\snapshot_metadata [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\snapshot_metadata;done=false>>]
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\snapshot_metadata
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\index [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\index;done=false>>]
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1330142 INFO (coreCloseExecutor-4091-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-001\schemaless\solr\gettingstarted\data\index
[junit4] 2> 1330144 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4cbbf7c4{/solr,null,UNAVAILABLE}
[junit4] 2> 1330146 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.u.TestSolrCLIRunExample Stopped standalone Solr instance running on port 50087
[junit4] 2> 1330146 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemalessExample
[junit4] 2> 1330147 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 1332164 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1332165 INFO (Thread-2029) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1332165 INFO (Thread-2029) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1332265 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.ZkTestServer start zk server on port:50102
[junit4] 2> 1332265 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1332267 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1332271 INFO (zkCallback-1634-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@43c42019 name:ZooKeeperConnection Watcher:127.0.0.1:50102 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1332271 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1332271 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1332272 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1332278 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x156e0d864cd0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1332280 INFO (jetty-launcher-1633-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1332281 INFO (jetty-launcher-1633-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@50531cc2{/solr,null,AVAILABLE}
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@6a26210{HTTP/1.1,[http/1.1]}{127.0.0.1:50097}
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.e.j.s.Server Started @1337213ms
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50097}
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1'
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1332282 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1332283 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1332285 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1332287 INFO (zkCallback-1635-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44ea71c5 name:ZooKeeperConnection Watcher:127.0.0.1:50102/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1332287 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1332287 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1332287 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1332301 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1
[junit4] 2> 1332302 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 627546187
[junit4] 2> 1332302 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1]
[junit4] 2> 1332302 WARN (jetty-launcher-1633-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\lib
[junit4] 2> 1332302 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1332305 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1332306 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1332306 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1332306 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50102/solr
[junit4] 2> 1332306 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1332306 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1332307 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1332309 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@32b7cea2 name:ZooKeeperConnection Watcher:127.0.0.1:50102 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1332309 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1332309 INFO (jetty-launcher-1633-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1332312 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$61/2084033713@3a3a7572
[junit4] 2> 1332312 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1332314 INFO (zkCallback-1639-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4beb8061 name:ZooKeeperConnection Watcher:127.0.0.1:50102/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1332314 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1332315 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1332317 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1332321 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1332323 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1332326 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1332328 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1332330 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1332331 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1332333 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1332334 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1332334 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1332334 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1332334 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1332341 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1332342 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1332344 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1332344 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96511662043758595-localhost:50097_solr-n_0000000000
[junit4] 2> 1332344 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:50097_solr
[junit4] 2> 1332344 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1332347 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.Overseer Overseer (id=96511662043758595-localhost:50097_solr-n_0000000000) starting
[junit4] 2> 1332349 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1332355 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1332356 INFO (OverseerCollectionConfigSetProcessor-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1332356 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:50097_solr
[junit4] 2> 1332356 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/localhost:50097_solr
[junit4] 2> 1332356 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1332359 INFO (zkCallback-1639-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1332360 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1332361 INFO (zkCallback-1639-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 1332380 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1332380 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1332381 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1
[junit4] 2> 1332383 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1332384 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1332384 INFO (jetty-launcher-1633-thread-1) [n:localhost:50097_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1332384 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1332385 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1332386 INFO (zkCallback-1641-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d50ace name:ZooKeeperConnection Watcher:127.0.0.1:50102 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1332386 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1332386 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1335408 INFO (qtp910842596-9217) [n:localhost:50097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=17
[junit4] 2> 1335412 INFO (qtp910842596-9221) [n:localhost:50097_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 1335419 INFO (qtp910842596-9221) [n:localhost:50097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=7
[junit4] 2> 1335419 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1335419 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1335424 INFO (zkCallback-1645-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1de3c26c name:ZooKeeperConnection Watcher:127.0.0.1:50102/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1335424 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1335424 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1335424 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1335425 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1335426 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1335427 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x156e0d864cd0005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1335432 INFO (qtp910842596-9220) [n:localhost:50097_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 1335432 INFO (qtp910842596-9220) [n:localhost:50097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1335441 INFO (qtp910842596-9220) [n:localhost:50097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=5
[junit4] 2> 1335443 INFO (qtp910842596-9222) [n:localhost:50097_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 1335443 INFO (qtp910842596-9222) [n:localhost:50097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 1335443 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1335447 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1335449 INFO (zkCallback-1648-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42a517c3 name:ZooKeeperConnection Watcher:127.0.0.1:50102/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1335449 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1335449 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1335449 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1335450 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1335450 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1335456 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/currency.xml
[junit4] 2> 1335458 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/elevate.xml
[junit4] 2> 1335460 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ca.txt
[junit4] 2> 1335463 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_fr.txt
[junit4] 2> 1335465 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ga.txt
[junit4] 2> 1335468 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_it.txt
[junit4] 2> 1335471 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/hyphenations_ga.txt
[junit4] 2> 1335473 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stemdict_nl.txt
[junit4] 2> 1335476 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stoptags_ja.txt
[junit4] 2> 1335479 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ar.txt
[junit4] 2> 1335482 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_bg.txt
[junit4] 2> 1335484 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ca.txt
[junit4] 2> 1335486 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_cz.txt
[junit4] 2> 1335488 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_da.txt
[junit4] 2> 1335491 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_de.txt
[junit4] 2> 1335495 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_el.txt
[junit4] 2> 1335498 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_en.txt
[junit4] 2> 1335500 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_es.txt
[junit4] 2> 1335501 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_eu.txt
[junit4] 2> 1335504 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fa.txt
[junit4] 2> 1335508 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fi.txt
[junit4] 2> 1335510 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fr.txt
[junit4] 2> 1335512 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ga.txt
[junit4] 2> 1335515 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_gl.txt
[junit4] 2> 1335517 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hi.txt
[junit4] 2> 1335519 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hu.txt
[junit4] 2> 1335523 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hy.txt
[junit4] 2> 1335525 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_id.txt
[junit4] 2> 1335528 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_it.txt
[junit4] 2> 1335532 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ja.txt
[junit4] 2> 1335535 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_lv.txt
[junit4] 2> 1335542 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_nl.txt
[junit4] 2> 1335545 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_no.txt
[junit4] 2> 1335546 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_pt.txt
[junit4] 2> 1335548 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ro.txt
[junit4] 2> 1335551 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ru.txt
[junit4] 2> 1335554 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_sv.txt
[junit4] 2> 1335556 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_th.txt
[junit4] 2> 1335558 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_tr.txt
[junit4] 2> 1335561 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/userdict_ja.txt
[junit4] 2> 1335564 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1335566 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/params.json
[junit4] 2> 1335569 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/protwords.txt
[junit4] 2> 1335570 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/solrconfig.xml
[junit4] 2> 1335572 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/stopwords.txt
[junit4] 2> 1335575 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[5F5C56A304A40F7A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/synonyms.txt
[junit4] 2> 1335581 INFO (qtp910842596-9223) [n:localhost:50097_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 1335581 INFO (qtp910842596-9223) [n:localhost:50097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1335585 INFO (qtp910842596-9217) [n:localhost:50097_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
[junit4] 2> 1335590 INFO (OverseerCollectionConfigSetProcessor-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1335590 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1335590 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Create collection testCloudExamplePrompt
[junit4] 2> 1335591 WARN (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1335592 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testCloudExamplePrompt
[junit4] 2> 1335592 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testCloudExamplePrompt
[junit4] 2> 1335594 INFO (zkCallback-1639-thread-3-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1335594 INFO (zkCallback-1648-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1335600 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1335600 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCloudExamplePrompt
[junit4] 2> 1335600 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCloudExamplePrompt/state.json
[junit4] 2> 1335698 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Creating SolrCores for new collection testCloudExamplePrompt, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 1335699 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard1_replica2 as part of shard shard1 of collection testCloudExamplePrompt on localhost:50097_solr
[junit4] 2> 1335699 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard2_replica2 as part of shard shard2 of collection testCloudExamplePrompt on localhost:50097_solr
[junit4] 2> 1335699 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard2_replica1 as part of shard shard2 of collection testCloudExamplePrompt on localhost:50097_solr
[junit4] 2> 1335699 INFO (OverseerThreadFactory-4098-thread-1-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard1_replica1 as part of shard shard1 of collection testCloudExamplePrompt on localhost:50097_solr
[junit4] 2> 1335702 INFO (qtp910842596-9219) [n:localhost:50097_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1335703 INFO (qtp910842596-9222) [n:localhost:50097_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1335703 INFO (qtp910842596-9219) [n:localhost:50097_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data\, shard=shard2}
[junit4] 2> 1335703 INFO (qtp910842596-9224) [n:localhost:50097_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1335705 INFO (qtp910842596-9223) [n:localhost:50097_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1335705 INFO (qtp910842596-9222) [n:localhost:50097_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data\, shard=shard1}
[junit4] 2> 1335705 INFO (qtp910842596-9224) [n:localhost:50097_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data\, shard=shard2}
[junit4] 2> 1335705 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1335706 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1335707 INFO (qtp910842596-9223) [n:localhost:50097_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data\, shard=shard1}
[junit4] 2> 1335707 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1335708 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1335708 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1335709 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Add data for [testCloudExamplePrompt] ver [0]
[junit4] 2> 1335709 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1335709 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1335709 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1335709 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1335709 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1335710 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1335710 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1335711 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1335711 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1335713 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1335713 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1335716 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1335716 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1335720 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1335720 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:50097/solr",
[junit4] 2> "node_name":"localhost:50097_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1335824 INFO (OverseerStateUpdate-96511662043758595-localhost:50097_solr-n_0000000000) [n:localhost:50097_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCloudExamplePrompt/state.json version: 0
[junit4] 2> 1335824 INFO (zkCallback-1639-thread-3-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1335825 INFO (zkCallback-1639-thread-3-processing-n:localhost:50097_solr) [n:localhost:50097_solr ] o.a.s.c.c.ZkStateReader Updating data for [testCloudExamplePrompt] from [0] to [1]
[junit4] 2> 1336710 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica2
[junit4] 2> 1336710 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica2
[junit4] 2> 1336710 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica1
[junit4] 2> 1336710 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1336710 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1336710 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1336710 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica1
[junit4] 2> 1336710 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1336711 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1336711 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1336711 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1336711 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1336711 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1336711 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1336711 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1336711 INFO (qtp910842596-9223) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1336712 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1336712 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica1'
[junit4] 2> 1336712 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1336712 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2'
[junit4] 2> 1336712 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1336712 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1336712 INFO (qtp910842596-9219) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1336712 INFO (qtp910842596-9224) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1336712 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1336712 INFO (qtp910842596-9222) [n:localhost:50097_solr c:testCloudExamplePrompt s:s
[...truncated too long message...]
ctReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:850)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:503)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:940)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2578)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.lambda$handleGET$0(SolrConfigHandler.java:216)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\ [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\;done=false>>]
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\snapshot_metadata [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\snapshot_metadata;done=false>>]
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\snapshot_metadata
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\index [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\index;done=false>>]
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\index
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.CachingDirectoryFactory Removing directory after core close: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_5F5C56A304A40F7A-001\tempDir-003\node1\testCloudExamplePrompt_shard2_replica2\data\
[junit4] 2> 1350271 WARN (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.SolrCore
[junit4] 2> org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'managed-schema' in classpath or '/configs/testCloudExamplePrompt', cwd=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:130)
[junit4] 2> at org.apache.solr.schema.SchemaManager.getFreshManagedSchema(SchemaManager.java:429)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2571)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$3(ZkController.java:2443)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1350271 INFO (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.SolrCore core reload testCloudExamplePrompt_shard2_replica2
[junit4] 2> 1350271 WARN (Thread-2046) [n:localhost:50097_solr ] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: No such core: testCloudExamplePrompt_shard2_replica2
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:933)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2578)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$3(ZkController.java:2443)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Aug 31, 2016 1:45:11 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=2006, maxMBSortInHeap=7.422021429633047, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=lt-LT, timezone=AET
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=76380544,total=336850944
[junit4] 2> NOTE: All tests run in this JVM: [TestDFRSimilarityFactory, BitVectorTest, SyncSliceTest, TestDocBasedVersionConstraints, TestGraphTermsQParserPlugin, TestLFUCache, TestDistributedSearch, PathHierarchyTokenizerFactoryTest, HighlighterTest, TestMergePolicyConfig, SolrRequestParserTest, ExitableDirectoryReaderTest, DisMaxRequestHandlerTest, TestNamedUpdateProcessors, BasicDistributedZkTest, UpdateParamsTest, TestInfoStreamLogging, RestartWhileUpdatingTest, StressHdfsTest, StatelessScriptUpdateProcessorFactoryTest, TestQuerySenderNoQuery, SharedFSAutoReplicaFailoverUtilsTest, SolrIndexConfigTest, TestRuleBasedAuthorizationPlugin, TestRequestForwarding, CurrencyFieldXmlFileTest, DistribJoinFromCollectionTest, TestStressVersions, TestLuceneMatchVersion, ParsingFieldUpdateProcessorsTest, OutOfBoxZkACLAndCredentialsProvidersTest, HttpPartitionTest, TestCodecSupport, SpatialFilterTest, TestStressReorder, HdfsThreadLeakTest, TestMiniSolrCloudClusterBase, TestSerializedLuceneMatchVersion, IndexSchemaTest, SliceStateTest, TriLevelCompositeIdRoutingTest, TestDistribIDF, TermsComponentTest, TestBlobHandler, TestStandardQParsers, TestSortByMinMaxFunction, ChaosMonkeyNothingIsSafeTest, TestRemoteStreaming, ActionThrottleTest, TestTolerantUpdateProcessorRandomCloud, TestReqParamsAPI, TestSolrCloudWithKerberosAlt, TestComponentsName, TestFaceting, WrapperMergePolicyFactoryTest, RegexBoostProcessorTest, TestRandomCollapseQParserPlugin, DistributedQueryElevationComponentTest, QueryElevationComponentTest, UUIDFieldTest, TestSolr4Spatial2, TestExpandComponent, URLClassifyProcessorTest, TestRebalanceLeaders, TestJsonRequest, TestPartialUpdateDeduplication, SortSpecParsingTest, TestPhraseSuggestions, TestMinMaxOnMultiValuedField, TestElisionMultitermQuery, DocValuesMissingTest, XmlUpdateRequestHandlerTest, CircularListTest, BlockJoinFacetDistribTest, HdfsUnloadDistributedZkTest, BigEndianAscendingWordDeserializerTest, TestRandomDVFaceting, TestDocSet, DocumentAnalysisRequestHandlerTest, XsltUpdateRequestHandlerTest, SolrTestCaseJ4Test, TestSQLHandler, SolrCLIZkUtilsTest, TestSolrConfigHandlerConcurrent, DistributedFacetPivotLongTailTest, TestHdfsUpdateLog, ReturnFieldsTest, DirectSolrSpellCheckerTest, TestExactStatsCache, CollectionTooManyReplicasTest, ChangedSchemaMergeTest, AnalysisAfterCoreReloadTest, DocExpirationUpdateProcessorFactoryTest, TestCSVLoader, TestLMDirichletSimilarityFactory, ClusterStateTest, StandardRequestHandlerTest, ClusterStateUpdateTest, TestSearchPerf, ShardSplitTest, HdfsCollectionsAPIDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType, TestXmlQParserPlugin, TestCoreContainer, ReplaceNodeTest, DistributedSuggestComponentTest, TestSolrCLIRunExample]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCLIRunExample -Dtests.seed=5F5C56A304A40F7A -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=AET -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestSolrCLIRunExample (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper, SolrCore]
[junit4] > at __randomizedtesting.SeedInfo.seed([5F5C56A304A40F7A]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:258)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [271/631 (1!)] on J0 in 26.29s, 3 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 61372 lines...]