lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build # 552 - Unstable!
Date Mon, 31 Oct 2016 10:14:53 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/552/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.core.TestDynamicLoading.testDynamicLoading

Error Message:
Could not get expected value  'X val changed' for path 'x' full output: {   "responseHeader":{     "status":0,     "QTime":0},   "params":{"wt":"json"},   "context":{     "webapp":"",     "path":"/test1",     "httpMethod":"GET"},   "class":"org.apache.solr.core.BlobStoreTestRequestHandler",   "x":"X val"},  from server:  null

Stack Trace:
java.lang.AssertionError: Could not get expected value  'X val changed' for path 'x' full output: {
  "responseHeader":{
    "status":0,
    "QTime":0},
  "params":{"wt":"json"},
  "context":{
    "webapp":"",
    "path":"/test1",
    "httpMethod":"GET"},
  "class":"org.apache.solr.core.BlobStoreTestRequestHandler",
  "x":"X val"},  from server:  null
	at __randomizedtesting.SeedInfo.seed([957680B73009894C:4D3BADE0C7D42CEC]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:535)
	at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:249)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	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 12425 lines...]
   [junit4] Suite: org.apache.solr.core.TestDynamicLoading
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\init-core-data-001
   [junit4]   2> 2660958 INFO  (SUITE-TestDynamicLoading-seed#[957680B73009894C]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2660958 INFO  (SUITE-TestDynamicLoading-seed#[957680B73009894C]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2660963 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2660963 INFO  (Thread-3831) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2660963 INFO  (Thread-3831) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2661064 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkTestServer start zk server on port:60067
   [junit4]   2> 2661102 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2661110 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2661114 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2661119 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2661123 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2661126 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2661131 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2661135 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2661139 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2661144 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2661148 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2661153 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 0x1581a2293880001, 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> 2661997 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\control-001\cores\collection1
   [junit4]   2> 2662000 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2662002 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1615811{/,null,AVAILABLE}
   [junit4]   2> 2662005 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@12d5049{HTTP/1.1,[http/1.1]}{127.0.0.1:60086}
   [junit4]   2> 2662005 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server Started @2665357ms
   [junit4]   2> 2662005 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\tempDir-001/control/data, hostContext=/, hostPort=60086, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\control-001\cores}
   [junit4]   2> 2662006 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2662006 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 2662006 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2662006 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2662007 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-31T09:47:04.471Z
   [junit4]   2> 2662016 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2662016 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\control-001\solr.xml
   [junit4]   2> 2662055 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2662057 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60067/solr
   [junit4]   2> 2662113 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60086_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60086_
   [junit4]   2> 2662116 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60086_    ] o.a.s.c.Overseer Overseer (id=96856127794839556-127.0.0.1:60086_-n_0000000000) starting
   [junit4]   2> 2662133 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60086_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60086_
   [junit4]   2> 2662138 INFO  (OverseerStateUpdate-96856127794839556-127.0.0.1:60086_-n_0000000000) [n:127.0.0.1:60086_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2662187 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60086_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\control-001\cores
   [junit4]   2> 2662187 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60086_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2662193 INFO  (OverseerStateUpdate-96856127794839556-127.0.0.1:60086_-n_0000000000) [n:127.0.0.1:60086_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2663234 WARN  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2663235 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 2663291 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2663473 WARN  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2663480 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2663522 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 2663524 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 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.core.TestDynamicLoading_957680B73009894C-001\control-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\control-001\cores\collection1\data\]
   [junit4]   2> 2663524 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5dc802
   [junit4]   2> 2663526 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=27.884765625, floorSegmentMB=0.353515625, forceMergeDeletesPctAllowed=11.880464942276273, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5755229268825173
   [junit4]   2> 2663538 WARN  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2663568 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2663568 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2663571 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2663571 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2663572 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2663573 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dd99f0[collection1] main]
   [junit4]   2> 2663576 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2663576 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2663577 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2663581 INFO  (searcherExecutor-8686-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1dd99f0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2663581 INFO  (coreLoadExecutor-8685-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549698047457361920
   [junit4]   2> 2663597 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2663597 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2663597 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60086/collection1/
   [junit4]   2> 2663597 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2663597 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60086/collection1/ has no replicas
   [junit4]   2> 2663607 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60086/collection1/ shard1
   [junit4]   2> 2663700 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2663703 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2663759 INFO  (coreZkRegister-8678-thread-1-processing-n:127.0.0.1:60086_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60086_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2664425 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-1-001\cores\collection1
   [junit4]   2> 2664431 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-1-001
   [junit4]   2> 2664431 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2664432 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1118155{/,null,AVAILABLE}
   [junit4]   2> 2664435 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@15ebf6{HTTP/1.1,[http/1.1]}{127.0.0.1:60119}
   [junit4]   2> 2664435 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server Started @2667787ms
   [junit4]   2> 2664435 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=60119, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-1-001\cores}
   [junit4]   2> 2664435 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2664436 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 2664436 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2664436 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2664436 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-31T09:47:06.900Z
   [junit4]   2> 2664443 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2664443 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-1-001\solr.xml
   [junit4]   2> 2664462 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2664463 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60067/solr
   [junit4]   2> 2664483 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60119_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2664493 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60119_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60119_
   [junit4]   2> 2664496 INFO  (zkCallback-10305-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2664498 INFO  (zkCallback-10314-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2664498 INFO  (zkCallback-10309-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2664543 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60119_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-1-001\cores
   [junit4]   2> 2664543 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60119_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2664548 INFO  (OverseerStateUpdate-96856127794839556-127.0.0.1:60086_-n_0000000000) [n:127.0.0.1:60086_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 2665591 WARN  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2665593 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 2665624 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2665800 WARN  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2665803 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2665841 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2665842 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 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.core.TestDynamicLoading_957680B73009894C-001\shard-1-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-1-001\cores\collection1\data\]
   [junit4]   2> 2665842 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5dc802
   [junit4]   2> 2665844 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=27.884765625, floorSegmentMB=0.353515625, forceMergeDeletesPctAllowed=11.880464942276273, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5755229268825173
   [junit4]   2> 2665854 WARN  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2665885 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2665885 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2665887 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2665887 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2665889 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2665889 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@128a1b6[collection1] main]
   [junit4]   2> 2665892 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2665893 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2665894 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2665899 INFO  (searcherExecutor-8697-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@128a1b6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2665900 INFO  (coreLoadExecutor-8696-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549698049889009664
   [junit4]   2> 2665916 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2665917 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2665917 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60119/collection1/
   [junit4]   2> 2665917 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2665917 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60119/collection1/ has no replicas
   [junit4]   2> 2665925 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60119/collection1/ shard2
   [junit4]   2> 2666079 INFO  (coreZkRegister-8691-thread-1-processing-n:127.0.0.1:60119_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60119_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2666804 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-2-001\cores\collection1
   [junit4]   2> 2666807 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-2-001
   [junit4]   2> 2666808 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2666809 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ffe518{/,null,AVAILABLE}
   [junit4]   2> 2666810 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@1be5458{HTTP/1.1,[http/1.1]}{127.0.0.1:60143}
   [junit4]   2> 2666810 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server Started @2670162ms
   [junit4]   2> 2666810 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=60143, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-2-001\cores}
   [junit4]   2> 2666812 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2666812 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 2666812 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2666812 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2666812 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-31T09:47:09.276Z
   [junit4]   2> 2666818 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2666818 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-2-001\solr.xml
   [junit4]   2> 2666839 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2666840 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60067/solr
   [junit4]   2> 2666863 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60143_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2666873 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60143_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60143_
   [junit4]   2> 2666876 INFO  (zkCallback-10305-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2666876 INFO  (zkCallback-10309-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2666876 INFO  (zkCallback-10314-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2666877 INFO  (zkCallback-10320-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2666905 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60143_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-2-001\cores
   [junit4]   2> 2666906 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60143_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2666909 INFO  (OverseerStateUpdate-96856127794839556-127.0.0.1:60086_-n_0000000000) [n:127.0.0.1:60086_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2667939 WARN  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2667940 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 2667982 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2668136 WARN  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2668140 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2668178 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2668179 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 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.core.TestDynamicLoading_957680B73009894C-001\shard-2-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-2-001\cores\collection1\data\]
   [junit4]   2> 2668179 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5dc802
   [junit4]   2> 2668180 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=27.884765625, floorSegmentMB=0.353515625, forceMergeDeletesPctAllowed=11.880464942276273, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5755229268825173
   [junit4]   2> 2668187 WARN  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2668223 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2668223 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2668225 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2668225 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2668226 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2668227 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@122416d[collection1] main]
   [junit4]   2> 2668228 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2668229 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2668230 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2668232 INFO  (searcherExecutor-8708-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@122416d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2668233 INFO  (coreLoadExecutor-8707-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549698052335337472
   [junit4]   2> 2668244 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2668244 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2668244 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60143/collection1/
   [junit4]   2> 2668244 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2668244 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60143/collection1/ has no replicas
   [junit4]   2> 2668253 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60143/collection1/ shard1
   [junit4]   2> 2668406 INFO  (coreZkRegister-8702-thread-1-processing-n:127.0.0.1:60143_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60143_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2669025 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-3-001\cores\collection1
   [junit4]   2> 2669028 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-3-001
   [junit4]   2> 2669029 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2669031 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15d8664{/,null,AVAILABLE}
   [junit4]   2> 2669035 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@f4a423{HTTP/1.1,[http/1.1]}{127.0.0.1:60167}
   [junit4]   2> 2669035 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server Started @2672387ms
   [junit4]   2> 2669035 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/, hostPort=60167, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-3-001\cores}
   [junit4]   2> 2669036 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2669036 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 2669036 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2669036 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2669036 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-31T09:47:11.500Z
   [junit4]   2> 2669042 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2669042 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-3-001\solr.xml
   [junit4]   2> 2669058 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2669060 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60067/solr
   [junit4]   2> 2669082 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60167_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 2669093 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60167_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60167_
   [junit4]   2> 2669097 INFO  (zkCallback-10305-thread-2-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2669097 INFO  (zkCallback-10309-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2669098 INFO  (zkCallback-10326-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2669100 INFO  (zkCallback-10320-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2669100 INFO  (zkCallback-10314-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2669128 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60167_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-3-001\cores
   [junit4]   2> 2669128 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60167_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2669131 INFO  (OverseerStateUpdate-96856127794839556-127.0.0.1:60086_-n_0000000000) [n:127.0.0.1:60086_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 2670151 WARN  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2670152 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 2670174 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2670315 WARN  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2670318 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2670345 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2670345 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 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.core.TestDynamicLoading_957680B73009894C-001\shard-3-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-3-001\cores\collection1\data\]
   [junit4]   2> 2670345 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5dc802
   [junit4]   2> 2670347 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=27.884765625, floorSegmentMB=0.353515625, forceMergeDeletesPctAllowed=11.880464942276273, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5755229268825173
   [junit4]   2> 2670354 WARN  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2670380 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2670380 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2670381 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2670381 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2670382 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2670382 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e2bf87[collection1] main]
   [junit4]   2> 2670384 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2670385 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2670385 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2670387 INFO  (searcherExecutor-8719-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e2bf87[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2670387 INFO  (coreLoadExecutor-8718-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549698054593970176
   [junit4]   2> 2670394 INFO  (coreZkRegister-8713-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
   [junit4]   2> 2670395 INFO  (updateExecutor-10323-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 2670395 INFO  (recoveryExecutor-10324-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 2670396 INFO  (recoveryExecutor-10324-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
   [junit4]   2> 2670396 INFO  (recoveryExecutor-10324-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
   [junit4]   2> 2670396 INFO  (recoveryExecutor-10324-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 2670396 INFO  (recoveryExecutor-10324-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:60119/collection1/] and I am [http://127.0.0.1:60167/collection1/]
   [junit4]   2> 2670400 INFO  (recoveryExecutor-10324-thread-1-processing-n:127.0.0.1:60167_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60167_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:60119]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:60167_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 2670405 INFO  (qtp1383266-43874) [n:127.0.0.1:60119_    ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
   [junit4]   2> 2670406 INFO  (qtp1383266-43874) [n:127.0.0.1:60119_    ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard2 of collection1) have state: recovering
   [junit4]   2> 2670406 INFO  (qtp1383266-43874) [n:127.0.0.1:60119_    ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:60167_, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:60167","node_name":"127.0.0.1:60167_","state":"down"}
   [junit4]   2> 2671208 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-4-001\cores\collection1
   [junit4]   2> 2671211 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-4-001
   [junit4]   2> 2671212 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2671213 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d2554{/,null,AVAILABLE}
   [junit4]   2> 2671215 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@17a9a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:60193}
   [junit4]   2> 2671215 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.e.j.s.Server Started @2674568ms
   [junit4]   2> 2671216 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=60193, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-4-001\cores}
   [junit4]   2> 2671216 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2671217 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 2671217 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2671217 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2671217 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-31T09:47:13.681Z
   [junit4]   2> 2671224 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2671224 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] 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.core.TestDynamicLoading_957680B73009894C-001\shard-4-001\solr.xml
   [junit4]   2> 2671245 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2671246 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60067/solr
   [junit4]   2> 2671265 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60193_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2671275 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60193_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60193_
   [junit4]   2> 2671280 INFO  (zkCallback-10333-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2671280 INFO  (zkCallback-10320-thread-1-processing-n:127.0.0.1:60143_) [n:127.0.0.1:60143_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2671280 INFO  (zkCallback-10314-thread-1-processing-n:127.0.0.1:60119_) [n:127.0.0.1:60119_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2671280 INFO  (zkCallback-10305-thread-1-processing-n:127.0.0.1:60086_) [n:127.0.0.1:60086_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2671282 INFO  (zkCallback-10309-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2671282 INFO  (zkCallback-10326-thread-1-processing-n:127.0.0.1:60167_) [n:127.0.0.1:60167_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2671318 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60193_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-4-001\cores
   [junit4]   2> 2671318 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [n:127.0.0.1:60193_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2671320 INFO  (OverseerStateUpdate-96856127794839556-127.0.0.1:60086_-n_0000000000) [n:127.0.0.1:60086_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2671407 INFO  (qtp1383266-43874) [n:127.0.0.1:60119_    ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:60167_, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:60167","node_name":"127.0.0.1:60167_","state":"recovering"}
   [junit4]   2> 2671407 INFO  (qtp1383266-43874) [n:127.0.0.1:60119_    ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
   [junit4]   2> 2671407 INFO  (qtp1383266-43874) [n:127.0.0.1:60119_    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:60167_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1000
   [junit4]   2> 2672342 WARN  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2672342 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 2672363 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2672519 WARN  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2672524 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2672557 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2672557 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 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.core.TestDynamicLoading_957680B73009894C-001\shard-4-001\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001\shard-4-001\cores\collection1\data\]
   [junit4]   2> 2672558 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5dc802
   [junit4]   2> 2672559 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=27.884765625, floorSegmentMB=0.353515625, forceMergeDeletesPctAllowed=11.880464942276273, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5755229268825173
   [junit4]   2> 2672566 WARN  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2672595 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2672595 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2672596 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2672597 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2672597 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2672599 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b39d36[collection1] main]
   [junit4]   2> 2672601 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2672601 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2672601 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2672604 INFO  (searcherExecutor-8730-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b39d36[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2672604 INFO  (coreLoadExecutor-8729-thread-1-processing-n:127.0.0.1:60193_) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549698056918663168
   [junit4]   2> 2672610 INFO  (coreZkRegister-8724-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
   [junit4]   2> 2672611 INFO  (updateExecutor-10330-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 2672612 INFO  (recoveryExecutor-10331-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 2672612 INFO  (recoveryExecutor-10331-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
   [junit4]   2> 2672612 INFO  (recoveryExecutor-10331-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
   [junit4]   2> 2672612 INFO  (recoveryExecutor-10331-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 2672612 INFO  (recoveryExecutor-10331-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:60143/collection1/] and I am [http://127.0.0.1:60193/collection1/]
   [junit4]   2> 2672616 INFO  (recoveryExecutor-10331-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:60143]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:60193_&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 2672622 INFO  (qtp20103581-43904) [n:127.0.0.1:60143_    ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
   [junit4]   2> 2672623 INFO  (qtp20103581-43904) [n:127.0.0.1:60143_    ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering
   [junit4]   2> 2672623 INFO  (qtp20103581-43904) [n:127.0.0.1:60143_    ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:60193_, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{"core":"collection1","base_url":"http://127.0.0.1:60193","node_name":"127.0.0.1:60193_","state":"down"}
   [junit4]   2> 2672822 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDynamicLoading
   [junit4]   2> 2672826 INFO  (TEST-TestDynamicLoading.testDynamicLoading-seed#[957680B73009894C]) [    ] o.a.s.c.TestSolrConfigHandler going to send config command. path /config?wt=json , payload: 
   [junit4]   2> 2672836 INFO  (qtp990864-43964) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted to ZK [{"add-runtimelib":{
   [junit4]   2>     "name":"colltest",
   [junit4]   2>     "version":1}}]
   [junit4]   2> 2672836 INFO  (qtp990864-43964) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 2 replicas to set the property overlay to be of version 0 for collection collection1
   [junit4]   2> 2672842 INFO  (Thread-3881) [n:127.0.0.1:60193_    ] o.a.s.c.SolrCore config update listener called for core collection1
   [junit4]   2> 2672842 INFO  (Thread-3882) [n:127.0.0.1:60143_    ] o.a.s.c.SolrCore config update listener called for core collection1
   [junit4]   2> 2672843 INFO  (Thread-3883) [n:127.0.0.1:60086_    ] o.a.s.c.SolrCore config update listener called for core collection1
   [junit4]   2> 2672843 INFO  (Thread-3884) [n:127.0.0.1:60119_    ] o.a.s.c.SolrCore config update listener called for core collection1
   [junit4]   2> 2672844 INFO  (Thread-3885) [n:127.0.0.1:60167_    ] o.a.s.c.SolrCore config update listener called for core collection1
   [junit4]   2> 2672844 INFO  (Thread-3881) [n:127.0.0.1:60193_    ] o.a.s.s.SchemaManager managed schema loaded . version : 0 
   [junit4]   2> 2672845 INFO  (Thread-3882) [n:127.0.0.1:60143_    ] o.a.s.s.SchemaManager managed schema loaded . version : 0 
   [junit4]   2> 2672845 ERROR (Thread-3882) [n:127.0.0.1:60143_    ] o.a.s.s.IndexSchema This IndexSchema is not mutable.
   [junit4]   2> 2672845 ERROR (Thread-3881) [n:127.0.0.1:60193_    ] o.a.s.s.IndexSchema This IndexSchema is not mutable.
   [junit4]   2> 2672845 WARN  (Thread-3882) [n:127.0.0.1:60143_    ] o.a.s.c.SolrCore 
   [junit4]   2> org.apache.solr.common.SolrException: This IndexSchema is not mutable.
   [junit4]   2> 	at org.apache.solr.schema.IndexSchema.getSchemaUpdateLock(IndexSchema.java:1872)
   [junit4]   2> 	at org.apache.solr.schema.SchemaManager.getFreshManagedSchema(SchemaManager.java:424)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2699)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2672845 INFO  (Thread-3882) [n:127.0.0.1:60143_    ] o.a.s.c.SolrCore core reload collection1
   [junit4]   2> 2672845 WARN  (Thread-3881) [n:127.0.0.1:60193_    ] o.a.s.c.SolrCore 
   [junit4]   2> org.apache.solr.common.SolrException: This IndexSchema is not mutable.
   [junit4]   2> 	at org.apache.solr.schema.IndexSchema.getSchemaUpdateLock(IndexSchema.java:1872)
   [junit4]   2> 	at org.apache.solr.schema.SchemaManager.getFreshManagedSchema(SchemaManager.java:424)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2699)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2672846 INFO  (Thread-3881) [n:127.0.0.1:60193_    ] o.a.s.c.SolrCore core reload collection1
   [junit4]   2> 2672847 INFO  (Thread-3883) [n:127.0.0.1:60086_    ] o.a.s.s.SchemaManager managed schema loaded . version : 0 
   [junit4]   2> 2672847 ERROR (Thread-3883) [n:127.0.0.1:60086_    ] o.a.s.s.IndexSchema This IndexSchema is not mutable.
   [junit4]   2> 2672847 WARN  (Thread-3883) [n:127.0.0.1:60086_    ] o.a.s.c.SolrCore 
   [junit4]   2> org.apache.solr.common.SolrException: This IndexSchema is not mutable.
   [junit4]   2> 	at org.apache.solr.schema.IndexSchema.getSchemaUpdateLock(IndexSchema.java:1872)
   [junit4]   2> 	at org.apache.solr.schema.SchemaManager.getFreshManagedSchema(SchemaManager.java:424)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2699)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2672847 INFO  (Thread-3883) [n:127.0.0.1:60086_    ] o.a.s.c.SolrCore core reload collection1
   [junit4]   2> 2672847 INFO  (solrHandlerExecutor-8735-thread-1-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
   [junit4]   2> 2672848 INFO  (solrHandlerExecutor-8735-thread-2-processing-n:127.0.0.1:60193_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:60193_ c:c

[...truncated too long message...]

erver connecting to 127.0.0.1:60067 60067
   [junit4]   2> 2709865 WARN  (Thread-3831) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	40	/solr/configs/conf1
   [junit4]   2> 	6	/solr/aliases.json
   [junit4]   2> 	6	/solr/clusterprops.json
   [junit4]   2> 	5	/solr/security.json
   [junit4]   2> 	4	/solr/collections/collection1/state.json
   [junit4]   2> 	2	/solr/configs/.system
   [junit4]   2> 	2	/solr/configs/.system/managed-schema
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	6	/solr/clusterstate.json
   [junit4]   2> 	2	/solr/collections/.system/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	50	/solr/overseer/queue
   [junit4]   2> 	42	/solr/overseer/collection-queue-work
   [junit4]   2> 	11	/solr/overseer/queue-work
   [junit4]   2> 	6	/solr/live_nodes
   [junit4]   2> 	6	/solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDynamicLoading -Dtests.method=testDynamicLoading -Dtests.seed=957680B73009894C -Dtests.slow=true -Dtests.locale=en-SG -Dtests.timezone=Europe/Volgograd -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 48.9s J0 | TestDynamicLoading.testDynamicLoading <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Could not get expected value  'X val changed' for path 'x' full output: {
   [junit4]    >   "responseHeader":{
   [junit4]    >     "status":0,
   [junit4]    >     "QTime":0},
   [junit4]    >   "params":{"wt":"json"},
   [junit4]    >   "context":{
   [junit4]    >     "webapp":"",
   [junit4]    >     "path":"/test1",
   [junit4]    >     "httpMethod":"GET"},
   [junit4]    >   "class":"org.apache.solr.core.BlobStoreTestRequestHandler",
   [junit4]    >   "x":"X val"},  from server:  null
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([957680B73009894C:4D3BADE0C7D42CEC]:0)
   [junit4]    > 	at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:535)
   [junit4]    > 	at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:249)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2709869 INFO  (SUITE-TestDynamicLoading-seed#[957680B73009894C]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_957680B73009894C-001
   [junit4]   2> Oct 31, 2016 9:47:52 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {blobName=PostingsFormat(name=Memory doPackFST= false), size=FSTOrd50, _version_=PostingsFormat(name=LuceneFixedGap), id=Lucene50(blocksize=128), version=PostingsFormat(name=Memory doPackFST= false), md5=PostingsFormat(name=LuceneFixedGap), timestamp=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=356, maxMBSortInHeap=7.189452687204673, sim=ClassicSimilarity, locale=en-SG, timezone=Europe/Volgograd
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=85115248,total=395051008
   [junit4]   2> NOTE: All tests run in this JVM: [TestCorePropertiesReload, LeaderElectionContextKeyTest, TestManagedSchemaAPI, TestBinaryField, PingRequestHandlerTest, HdfsThreadLeakTest, MoreLikeThisHandlerTest, TestFieldTypeResource, TestUninvertingReader, TestInfoStreamLogging, GraphQueryTest, TestFunctionQuery, BadComponentTest, AnalysisAfterCoreReloadTest, TestSQLHandlerNonCloud, TestSSLRandomization, TestDownShardTolerantSearch, AddSchemaFieldsUpdateProcessorFactoryTest, TestSort, SaslZkACLProviderTest, ExitableDirectoryReaderTest, TestGroupingSearch, BlockCacheTest, EnumFieldTest, RollingRestartTest, TestReplicationHandlerBackup, TestConfigReload, TestMiniSolrCloudCluster, TestCollationFieldDocValues, TestFieldCacheSanityChecker, TestDefaultStatsCache, TestStandardQParsers, SortSpecParsingTest, SynonymTokenizerTest, TestPseudoReturnFields, TestIBSimilarityFactory, HighlighterMaxOffsetTest, ParsingFieldUpdateProcessorsTest, TestConfigSetProperties, FastVectorHighlighterTest, TestPartialUpdateDeduplication, TestFieldCacheWithThreads, CdcrReplicationDistributedZkTest, IndexSchemaRuntimeFieldTest, InfoHandlerTest, TestLFUCache, TestFieldResource, TestJmxIntegration, ShardRoutingTest, TestCoreContainer, TestTolerantUpdateProcessorRandomCloud, HLLUtilTest, TestScoreJoinQPScore, BlockJoinFacetRandomTest, DirectSolrSpellCheckerTest, FullHLLTest, CustomCollectionTest, TestFieldCache, MergeStrategyTest, TestCloudInspectUtil, HdfsRecoverLeaseTest, DateFieldTest, DistributedFacetPivotSmallTest, OpenExchangeRatesOrgProviderTest, TestUniqueKeyFieldResource, CoreAdminRequestStatusTest, TestStressReorder, TestTrieFacet, TestStressLiveNodes, TestRemoteStreaming, TestSolrCloudSnapshots, ClassificationUpdateProcessorFactoryTest, TestStressLucene, DistributedQueryComponentCustomSortTest, ZkNodePropsTest, TestAnalyzedSuggestions, TestHdfsCloudBackupRestore, ForceLeaderTest, TestBulkSchemaConcurrent, ReplicationFactorTest, SharedFSAutoReplicaFailoverTest, DisMaxRequestHandlerTest, DOMUtilTest, TestLRUCache, HdfsChaosMonkeyNothingIsSafeTest, TestHighlightDedupGrouping, TestSizeLimitedDistributedMap, RecoveryZkTest, TestStressVersions, TestRTGBase, DistributedFacetPivotLongTailTest, TestCloudPseudoReturnFields, TestSolrConfigHandlerConcurrent, DistributedFacetExistsSmallTest, TestStressRecovery, TemplateUpdateProcessorTest, OutputWriterTest, TestRestoreCore, DeleteStatusTest, SolrCoreTest, TimeZoneUtilsTest, TestGraphTermsQParserPlugin, DirectUpdateHandlerTest, TestFieldSortValues, TestPKIAuthenticationPlugin, UnloadDistributedZkTest, TestLocalFSCloudBackupRestore, PluginInfoTest, TestOverriddenPrefixQueryForCustomFieldType, TestPerFieldSimilarity, PeerSyncTest, DirectSolrConnectionTest, TestCursorMarkWithoutUniqueKey, MultiThreadedOCPTest, LeaderFailureAfterFreshStartTest, OverseerModifyCollectionTest, DistanceUnitsTest, DateRangeFieldTest, TestFastOutputStream, TestDocBasedVersionConstraints, CollectionStateFormat2Test, LeaderInitiatedRecoveryOnShardRestartTest, AssignTest, AnalysisErrorHandlingTest, SyncSliceTest, TestRestManager, TestGeoJSONResponseWriter, MultiTermTest, TestDefaultSearchFieldResource, StressHdfsTest, TestConfigSetsAPIZkFailure, TestPhraseSuggestions, TestRawResponseWriter, TestExtendedDismaxParser, ChaosMonkeyNothingIsSafeTest, DocValuesTest, TestCollationField, TestManagedResource, HighlighterTest, QueryResultKeyTest, BufferStoreTest, PKIAuthenticationIntegrationTest, OverseerStatusTest, BitVectorTest, CurrencyFieldXmlFileTest, PrimitiveFieldTypeTest, RemoteQueryErrorTest, HdfsUnloadDistributedZkTest, SimpleFacetsTest, DistributedQueueTest, TestHashPartitioner, TestUpdate, DistribDocExpirationUpdateProcessorTest, LeaderElectionTest, PathHierarchyTokenizerFactoryTest, DocumentAnalysisRequestHandlerTest, TestFaceting, DistributedSpellCheckComponentTest, TestJoin, TestRangeQuery, StatsComponentTest, BasicFunctionalityTest, TestBadConfig, SoftAutoCommitTest, DistributedQueryElevationComponentTest, SuggesterTSTTest, TestTrie, TestCSVLoader, SchemaVersionSpecificBehaviorTest, QueryEqualityTest, SortByFunctionTest, DocValuesMultiTest, TestSolrDeletionPolicy1, DebugComponentTest, IndexBasedSpellCheckerTest, TestQueryUtils, StandardRequestHandlerTest, TestWriterPerf, TermsComponentTest, DocumentBuilderTest, TermVectorComponentTest, SolrPluginUtilsTest, JSONWriterTest, UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter, TestComponentsName, TestBinaryResponseWriter, SOLR749Test, TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, TestMergePolicyConfig, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, TestCharFilters, EchoParamsTest, TestDFRSimilarityFactory, TestBM25SimilarityFactory, TestFastLRUCache, PreAnalyzedFieldTest, TestSuggestSpellingConverter, TestSolrJ, TestDocumentBuilder, CursorPagingTest, TestSimpleTrackingShardHandler, TestTolerantSearch, ActionThrottleTest, BaseCdcrDistributedZkTest, CdcrBootstrapTest, CollectionsAPIAsyncDistributedZkTest, ConcurrentDeleteAndCreateCollectionTest, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, PeerSyncReplicationTest, TestAuthenticationFramework, TestClusterProperties, TestConfigSetsAPI, TestDeleteCollectionOnDownNodes, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudClusterSSL, TestRequestForwarding, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest, HdfsRestartWhileUpdatingTest, HdfsWriteToMultipleCollectionsTest, TestClusterStateMutator, ZkStateReaderTest, ZkStateWriterTest, RulesTest, TestDynamicLoading]
   [junit4] Completed [530/648 (1!)] on J0 in 49.76s, 1 test, 1 failure <<< FAILURES!

[...truncated 53068 lines...]


Mime
View raw message