Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4191/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.update.TestInPlaceUpdatesDistrib.test

Error Message:
This doc was supposed to have been deleted, but was: SolrDocument{id=0, 
title_s=title0, id_i=0, inplace_updatable_float=1.0, 
_version_=1579488647813529600, inplace_updatable_int_with_default=666, 
inplace_updatable_float_with_default=42.0}

Stack Trace:
java.lang.AssertionError: This doc was supposed to have been deleted, but was: 
SolrDocument{id=0, title_s=title0, id_i=0, inplace_updatable_float=1.0, 
_version_=1579488647813529600, inplace_updatable_int_with_default=666, 
inplace_updatable_float_with_default=42.0}
        at 
__randomizedtesting.SeedInfo.seed([B596B7031EB8B8B9:3DC288D9B044D541]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at 
org.apache.solr.update.TestInPlaceUpdatesDistrib.reorderedDBQsSimpleTest(TestInPlaceUpdatesDistrib.java:247)
        at 
org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpdatesDistrib.java:151)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13807 lines...]
   [junit4] Suite: org.apache.solr.update.TestInPlaceUpdatesDistrib
   [junit4]   2> 3511093 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/init-core-data-001
   [junit4]   2> 3511098 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 3511098 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 3511099 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /pik/i
   [junit4]   2> 3511103 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 3511104 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 3511149 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3511161 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=inplace-updates
   [junit4]   2> 3511170 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
   [junit4]   2> 3511226 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3511240 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3511241 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3511244 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 3511281 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3511295 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=inplace-updates
   [junit4]   2> 3511303 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with 
uniqueid field id
   [junit4]   2> 3511303 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 3511304 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3511304 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3511305 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/init-core-data-001/]
   [junit4]   2> 3511313 WARN  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3511381 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3511381 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 3511382 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3511382 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3511383 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@70e6432f[collection1] main]
   [junit4]   2> 3511384 WARN  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 3511385 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3511385 INFO  (coreLoadExecutor-11309-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1579488444228304896
   [junit4]   2> 3511386 INFO  
(searcherExecutor-11310-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@70e6432f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3511388 INFO  
(SUITE-TestInPlaceUpdatesDistrib-seed#[B596B7031EB8B8B9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 3511399 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 3511399 INFO  (Thread-4893) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 3511399 INFO  (Thread-4893) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 3511408 ERROR (Thread-4893) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 3511509 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:52123
   [junit4]   2> 3511555 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 3511558 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-inplace-updates.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 3511562 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 3511564 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 3511566 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 3511569 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 3511571 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 3511574 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 3511576 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 3511579 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 3511582 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 3511585 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly 
asked otherwise
   [junit4]   2> 3512086 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3512088 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@414ff317{/pik/i,null,AVAILABLE}
   [junit4]   2> 3512090 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6498019c{HTTP/1.1,[http/1.1]}{127.0.0.1:५२१२६}
   [junit4]   2> 3512091 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server Started @३५१६२२८ms
   [junit4]   2> 3512091 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/tempDir-001/control/data,
 hostContext=/pik/i, hostPort=52126, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/control-001/cores}
   [junit4]   2> 3512091 ERROR 
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3512092 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3512092 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3512092 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 3512092 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-25T05:32:43.028Z
   [junit4]   2> 3512096 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 3512096 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/control-001/solr.xml
   [junit4]   2> 3512102 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3512107 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52123/solr
   [junit4]   2> 3512266 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3512267 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.OverseerElectionContext I am going to 
be the leader 127.0.0.1:52126_pik%2Fi
   [junit4]   2> 3512269 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.Overseer Overseer 
(id=98718027765579780-127.0.0.1:52126_pik%2Fi-n_0000000000) starting
   [junit4]   2> 3512285 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52126_pik%2Fi
   [junit4]   2> 3512287 INFO  
(zkCallback-3922-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 3512371 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3512379 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3512380 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3512381 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/control-001/cores
   [junit4]   2> 3512422 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 3512424 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52123/solr ready
   [junit4]   2> 3512433 INFO  (qtp74296404-26278) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:52126_pik%252Fi&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3512438 INFO  
(OverseerThreadFactory-11320-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.CreateCollectionCmd Create collection 
control_collection
   [junit4]   2> 3512553 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3512554 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3512666 INFO  
(zkCallback-3922-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 3513579 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3513594 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema 
name=inplace-updates
   [junit4]   2> 3513602 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
   [junit4]   2> 3513602 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' 
using configuration from collection control_collection, trusted=true
   [junit4]   2> 3513603 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.control_collection.shard1.replica_n1' (registry 
'solr.core.control_collection.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3513603 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3513603 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/control-001/cores/control_collection_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/control-001/cores/control_collection_shard1_replica_n1/data/]
   [junit4]   2> 3513612 WARN  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3513666 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3513666 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3513667 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3513667 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3513670 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4e2592c3[control_collection_shard1_replica_n1] main]
   [junit4]   2> 3513671 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 3513671 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 3513672 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3513672 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1579488446626398208
   [junit4]   2> 3513673 INFO  
(searcherExecutor-11323-thread-1-processing-n:127.0.0.1:52126_pik%2Fi 
x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) 
[n:127.0.0.1:52126_pik%2Fi c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[control_collection_shard1_replica_n1] Registered new searcher 
Searcher@4e2592c3[control_collection_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3513679 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 3513679 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 3513679 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:52126/pik/i/control_collection_shard1_replica_n1/
   [junit4]   2> 3513680 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 3513680 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:52126/pik/i/control_collection_shard1_replica_n1/ has no 
replicas
   [junit4]   2> 3513680 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 3513684 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52126/pik/i/control_collection_shard1_replica_n1/ shard1
   [junit4]   2> 3513793 INFO  
(zkCallback-3922-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 3513848 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 3513850 INFO  (qtp74296404-26280) [n:127.0.0.1:52126_pik%2Fi 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1296
   [junit4]   2> 3513857 INFO  (qtp74296404-26278) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at 
most 30 seconds. Check all shard replicas
   [junit4]   2> 3513962 INFO  
(zkCallback-3922-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 3514450 INFO  
(OverseerCollectionConfigSetProcessor-98718027765579780-127.0.0.1:52126_pik%2Fi-n_0000000000)
 [n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3514857 INFO  (qtp74296404-26278) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:52126_pik%252Fi&wt=javabin&version=2}
 status=0 QTime=2424
   [junit4]   2> 3514863 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 3514865 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52123/solr ready
   [junit4]   2> 3514865 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 3514866 INFO  (qtp74296404-26281) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3514868 INFO  
(OverseerThreadFactory-11320-thread-2-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 3514869 WARN  
(OverseerThreadFactory-11320-thread-2-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.CreateCollectionCmd It is unusual to 
create a collection (collection1) without cores.
   [junit4]   2> 3515087 INFO  (qtp74296404-26281) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at 
most 30 seconds. Check all shard replicas
   [junit4]   2> 3515087 INFO  (qtp74296404-26281) [n:127.0.0.1:52126_pik%2Fi   
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2}
 status=0 QTime=221
   [junit4]   2> 3515374 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-1-001
 of type TLOG
   [junit4]   2> 3515374 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3515375 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@595c1a8c{/pik/i,null,AVAILABLE}
   [junit4]   2> 3515375 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@456c06b5{HTTP/1.1,[http/1.1]}{127.0.0.1:५२१३५}
   [junit4]   2> 3515375 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server Started @३५१९५१३ms
   [junit4]   2> 3515376 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/tempDir-001/jetty1,
 replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/pik/i, 
hostPort=52135, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-1-001/cores}
   [junit4]   2> 3515378 ERROR 
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3515378 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3515378 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3515378 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 3515378 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-25T05:32:46.314Z
   [junit4]   2> 3515381 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 3515381 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-1-001/solr.xml
   [junit4]   2> 3515386 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3515389 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52123/solr
   [junit4]   2> 3515401 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 3515404 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3515407 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52135_pik%2Fi
   [junit4]   2> 3515409 INFO  (zkCallback-3929-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 3515409 INFO  
(zkCallback-3922-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 3515412 INFO  
(zkCallback-3934-thread-1-processing-n:127.0.0.1:52135_pik%2Fi) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 3515516 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3515526 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3515526 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3515527 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-1-001/cores
   [junit4]   2> 3515574 INFO  (qtp1708104575-26319) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with 
params 
node=127.0.0.1:52135_pik%252Fi&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3515577 INFO  
(OverseerThreadFactory-11320-thread-3-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:52135_pik%2Fi for creating new replica
   [junit4]   2> 3515577 INFO  
(OverseerCollectionConfigSetProcessor-98718027765579780-127.0.0.1:52126_pik%2Fi-n_0000000000)
 [n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3515581 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t21&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 3515581 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3516607 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3516622 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.s.IndexSchema 
[collection1_shard1_replica_t21] Schema name=inplace-updates
   [junit4]   2> 3516631 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.s.IndexSchema 
Loaded schema inplace-updates/1.6 with uniqueid field id
   [junit4]   2> 3516631 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica_t21' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 3516631 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard1.replica_t21' (registry 
'solr.core.collection1.shard1.replica_t21') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3516632 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 3516632 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.SolrCore 
[[collection1_shard1_replica_t21] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-1-001/cores/collection1_shard1_replica_t21],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-1-001/cores/collection1_shard1_replica_t21/data/]
   [junit4]   2> 3516636 WARN  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3516694 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 3516694 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3516695 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 3516695 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 3516696 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@306509f1[collection1_shard1_replica_t21] main]
   [junit4]   2> 3516698 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 3516698 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 3516698 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3516699 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579488449800437760
   [junit4]   2> 3516699 INFO  
(searcherExecutor-11334-thread-1-processing-n:127.0.0.1:52135_pik%2Fi 
x:collection1_shard1_replica_t21 s:shard1 c:collection1) 
[n:127.0.0.1:52135_pik%2Fi c:collection1 s:shard1  
x:collection1_shard1_replica_t21] o.a.s.c.SolrCore 
[collection1_shard1_replica_t21] Registered new searcher 
Searcher@306509f1[collection1_shard1_replica_t21] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:52135/pik/i/collection1_shard1_replica_t21/
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.SyncStrategy 
http://127.0.0.1:52135/pik/i/collection1_shard1_replica_t21/ has no replicas
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 3516705 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.ZkController 
collection1_shard1_replica_t21 stopping background replication from leader
   [junit4]   2> 3516709 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52135/pik/i/collection1_shard1_replica_t21/ shard1
   [junit4]   2> 3516872 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 3516874 INFO  (qtp1708104575-26321) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t21] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t21&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=1293
   [junit4]   2> 3516877 INFO  (qtp1708104575-26319) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={node=127.0.0.1:52135_pik%252Fi&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
 status=0 QTime=1303
   [junit4]   2> 3517178 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-2-001
 of type TLOG
   [junit4]   2> 3517178 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3517180 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7360cba8{/pik/i,null,AVAILABLE}
   [junit4]   2> 3517180 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@33641428{HTTP/1.1,[http/1.1]}{127.0.0.1:५२१४१}
   [junit4]   2> 3517180 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server Started @३५२१३१८ms
   [junit4]   2> 3517180 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/tempDir-001/jetty2,
 replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/pik/i, 
hostPort=52141, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-2-001/cores}
   [junit4]   2> 3517182 ERROR 
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3517183 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3517183 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3517183 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 3517183 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-25T05:32:48.119Z
   [junit4]   2> 3517186 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 3517186 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-2-001/solr.xml
   [junit4]   2> 3517191 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3517195 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52123/solr
   [junit4]   2> 3517207 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 3517211 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3517214 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52141_pik%2Fi
   [junit4]   2> 3517216 INFO  
(zkCallback-3934-thread-1-processing-n:127.0.0.1:52135_pik%2Fi) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 3517216 INFO  
(zkCallback-3922-thread-2-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 3517216 INFO  (zkCallback-3929-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 3517218 INFO  
(zkCallback-3940-thread-1-processing-n:127.0.0.1:52141_pik%2Fi) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 3517309 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3517323 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3517324 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3517326 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-2-001/cores
   [junit4]   2> 3517380 INFO  (qtp1002630045-26349) [n:127.0.0.1:52141_pik%2Fi 
   ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with 
params 
node=127.0.0.1:52141_pik%252Fi&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3517384 INFO  
(OverseerThreadFactory-11320-thread-4-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:52141_pik%2Fi for creating new replica
   [junit4]   2> 3517384 INFO  
(OverseerCollectionConfigSetProcessor-98718027765579780-127.0.0.1:52126_pik%2Fi-n_0000000000)
 [n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3517387 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
   ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t23&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 3517388 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
   ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3518422 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3518447 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.s.IndexSchema 
[collection1_shard1_replica_t23] Schema name=inplace-updates
   [junit4]   2> 3518461 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.s.IndexSchema 
Loaded schema inplace-updates/1.6 with uniqueid field id
   [junit4]   2> 3518461 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica_t23' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 3518461 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard1.replica_t23' (registry 
'solr.core.collection1.shard1.replica_t23') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3518462 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 3518462 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.c.SolrCore 
[[collection1_shard1_replica_t23] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-2-001/cores/collection1_shard1_replica_t23],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-2-001/cores/collection1_shard1_replica_t23/data/]
   [junit4]   2> 3518470 WARN  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3518551 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 3518551 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3518552 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 3518552 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 3518554 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1186782d[collection1_shard1_replica_t23] main]
   [junit4]   2> 3518556 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 3518556 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 3518557 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3518557 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579488451748691968
   [junit4]   2> 3518560 INFO  
(searcherExecutor-11345-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1  
x:collection1_shard1_replica_t23] o.a.s.c.SolrCore 
[collection1_shard1_replica_t23] Registered new searcher 
Searcher@1186782d[collection1_shard1_replica_t23] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3518563 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.c.ZkController 
Core needs to recover:collection1_shard1_replica_t23
   [junit4]   2> 3518563 INFO  
(updateExecutor-3937-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 3518564 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Starting recovery 
process. recoveringAfterStartup=true
   [junit4]   2> 3518565 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy ###### 
startupVersions=[[]]
   [junit4]   2> 3518565 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.ZkController 
collection1_shard1_replica_t23 stopping background replication from leader
   [junit4]   2> 3518565 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Begin buffering 
updates. core=[collection1_shard1_replica_t23]
   [junit4]   2> 3518565 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 3518565 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Publishing state of 
core [collection1_shard1_replica_t23] as recovering, leader is 
[http://127.0.0.1:52135/pik/i/collection1_shard1_replica_t21/] and I am 
[http://127.0.0.1:52141/pik/i/collection1_shard1_replica_t23/]
   [junit4]   2> 3518565 INFO  (qtp1002630045-26351) [n:127.0.0.1:52141_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t23] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t23&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=1178
   [junit4]   2> 3518573 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Sending prep 
recovery command to [http://127.0.0.1:52135/pik/i]; [WaitForState: 
action=PREPRECOVERY&core=collection1_shard1_replica_t21&nodeName=127.0.0.1:52141_pik%252Fi&coreNodeName=core_node24&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 3518575 INFO  (qtp1708104575-26322) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node24, 
state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: 
true, maxTime: 183 s
   [junit4]   2> 3518576 INFO  (qtp1708104575-26322) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): 
collection=collection1, shard=shard1, thisCore=collection1_shard1_replica_t21, 
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, 
currentState=down, localState=active, nodeName=127.0.0.1:52141_pik%2Fi, 
coreNodeName=core_node24, onlyIfActiveCheckResult=false, nodeProps: 
core_node24:{"core":"collection1_shard1_replica_t23","base_url":"http://127.0.0.1:52141/pik/i","node_name":"127.0.0.1:52141_pik%2Fi","state":"down","type":"TLOG"}
   [junit4]   2> 3518577 INFO  (qtp1002630045-26349) [n:127.0.0.1:52141_pik%2Fi 
   ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={node=127.0.0.1:52141_pik%252Fi&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
 status=0 QTime=1197
   [junit4]   2> 3519022 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-3-001
 of type TLOG
   [junit4]   2> 3519023 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3519025 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3c38f19d{/pik/i,null,AVAILABLE}
   [junit4]   2> 3519025 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1b86dc6d{HTTP/1.1,[http/1.1]}{127.0.0.1:५२१४८}
   [junit4]   2> 3519028 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.e.j.s.Server Started @३५२३१६६ms
   [junit4]   2> 3519028 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/tempDir-001/jetty3,
 replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/pik/i, 
hostPort=52148, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-3-001/cores}
   [junit4]   2> 3519029 ERROR 
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3519029 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3519029 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3519029 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 3519030 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-25T05:32:49.966Z
   [junit4]   2> 3519034 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 3519034 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-3-001/solr.xml
   [junit4]   2> 3519042 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3519047 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52123/solr
   [junit4]   2> 3519064 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 3519069 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3519073 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52148_pik%2Fi
   [junit4]   2> 3519075 INFO  (zkCallback-3929-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 3519076 INFO  
(zkCallback-3940-thread-1-processing-n:127.0.0.1:52141_pik%2Fi) 
[n:127.0.0.1:52141_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 3519076 INFO  
(zkCallback-3922-thread-1-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 3519076 INFO  
(zkCallback-3934-thread-1-processing-n:127.0.0.1:52135_pik%2Fi) 
[n:127.0.0.1:52135_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 3519078 INFO  
(zkCallback-3947-thread-1-processing-n:127.0.0.1:52148_pik%2Fi) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 3519182 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3519209 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3519210 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3519213 INFO  
(TEST-TestInPlaceUpdatesDistrib.test-seed#[B596B7031EB8B8B9]) 
[n:127.0.0.1:52148_pik%2Fi    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-3-001/cores
   [junit4]   2> 3519305 INFO  (qtp1246037770-26382) [n:127.0.0.1:52148_pik%2Fi 
   ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with 
params 
node=127.0.0.1:52148_pik%252Fi&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3519309 INFO  
(OverseerThreadFactory-11320-thread-5-processing-n:127.0.0.1:52126_pik%2Fi) 
[n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:52148_pik%2Fi for creating new replica
   [junit4]   2> 3519309 INFO  
(OverseerCollectionConfigSetProcessor-98718027765579780-127.0.0.1:52126_pik%2Fi-n_0000000000)
 [n:127.0.0.1:52126_pik%2Fi    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3519313 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
   ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t25&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 3519314 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
   ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3519580 INFO  (qtp1708104575-26322) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): 
collection=collection1, shard=shard1, thisCore=collection1_shard1_replica_t21, 
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, 
currentState=recovering, localState=active, nodeName=127.0.0.1:52141_pik%2Fi, 
coreNodeName=core_node24, onlyIfActiveCheckResult=false, nodeProps: 
core_node24:{"core":"collection1_shard1_replica_t23","base_url":"http://127.0.0.1:52141/pik/i","node_name":"127.0.0.1:52141_pik%2Fi","state":"recovering","type":"TLOG"}
   [junit4]   2> 3519580 INFO  (qtp1708104575-26322) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node24, state: 
recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
   [junit4]   2> 3519580 INFO  (qtp1708104575-26322) [n:127.0.0.1:52135_pik%2Fi 
   ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={nodeName=127.0.0.1:52141_pik%252Fi&onlyIfLeaderActive=true&core=collection1_shard1_replica_t21&coreNodeName=core_node24&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
 status=0 QTime=1004
   [junit4]   2> 3520081 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Starting Replication 
Recovery.
   [junit4]   2> 3520081 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Attempting to 
replicate from [http://127.0.0.1:52135/pik/i/collection1_shard1_replica_t21/].
   [junit4]   2> 3520084 INFO  (qtp1708104575-26320) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1 r:core_node22 x:collection1_shard1_replica_t21] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579488453349867520,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3520085 INFO  (qtp1708104575-26320) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1 r:core_node22 x:collection1_shard1_replica_t21] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 3520085 INFO  (qtp1708104575-26320) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1 r:core_node22 x:collection1_shard1_replica_t21] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3520085 INFO  (qtp1708104575-26320) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1 r:core_node22 x:collection1_shard1_replica_t21] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21]  
webapp=/pik/i path=/update 
params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 3520087 INFO  (qtp1708104575-26322) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1 r:core_node22 x:collection1_shard1_replica_t21] 
o.a.s.c.S.Request [collection1_shard1_replica_t21]  webapp=/pik/i 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 3520087 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 3520087 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 3520088 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 3520088 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 3520088 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Replication Recovery 
was successful.
   [junit4]   2> 3520088 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Registering as 
Active after recovery.
   [junit4]   2> 3520088 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.ZkController 
collection1_shard1_replica_t23 starting background replication from leader
   [junit4]   2> 3520088 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.ReplicateFromLeader Will start 
replication from leader with poll interval: 00:00:03
   [junit4]   2> 3520089 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.ReplicationHandler Poll scheduled at 
an interval of 3000ms
   [junit4]   2> 3520089 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.ReplicationHandler Commits will be 
reserved for 10000ms.
   [junit4]   2> 3520089 INFO  (indexFetcher-11357-thread-1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Updated masterUrl to 
http://127.0.0.1:52135/pik/i/collection1_shard1_replica_t21/
   [junit4]   2> 3520089 INFO  (qtp1708104575-26323) [n:127.0.0.1:52135_pik%2Fi 
c:collection1 s:shard1 r:core_node22 x:collection1_shard1_replica_t21] 
o.a.s.c.S.Request [collection1_shard1_replica_t21]  webapp=/pik/i 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 3520090 INFO  (indexFetcher-11357-thread-1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 3520090 INFO  (indexFetcher-11357-thread-1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 3520090 INFO  (indexFetcher-11357-thread-1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 3520090 INFO  (indexFetcher-11357-thread-1) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 3520090 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Updating version 
bucket highest from index after successful recovery.
   [junit4]   2> 3520090 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1579488453356158976
   [junit4]   2> 3520092 INFO  
(recoveryExecutor-3938-thread-1-processing-n:127.0.0.1:52141_pik%2Fi 
x:collection1_shard1_replica_t23 s:shard1 c:collection1 r:core_node24) 
[n:127.0.0.1:52141_pik%2Fi c:collection1 s:shard1 r:core_node24 
x:collection1_shard1_replica_t23] o.a.s.c.RecoveryStrategy Finished recovery 
process, successful=[true]
   [junit4]   2> 3520337 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3520362 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.s.IndexSchema 
[collection1_shard1_replica_t25] Schema name=inplace-updates
   [junit4]   2> 3520366 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.s.IndexSchema 
Loaded schema inplace-updates/1.6 with uniqueid field id
   [junit4]   2> 3520367 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica_t25' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 3520367 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard1.replica_t25' (registry 
'solr.core.collection1.shard1.replica_t25') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1cd86a85
   [junit4]   2> 3520367 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 3520367 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.c.SolrCore 
[[collection1_shard1_replica_t25] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-3-001/cores/collection1_shard1_replica_t25],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001/shard-3-001/cores/collection1_shard1_replica_t25/data/]
   [junit4]   2> 3520399 WARN  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3520481 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 3520481 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3520482 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 3520483 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 3520484 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1eac607b[collection1_shard1_replica_t25] main]
   [junit4]   2> 3520486 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 3520486 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 3520505 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3520506 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579488453792366592
   [junit4]   2> 3520506 INFO  
(searcherExecutor-11358-thread-1-processing-n:127.0.0.1:52148_pik%2Fi 
x:collection1_shard1_replica_t25 s:shard1 c:collection1) 
[n:127.0.0.1:52148_pik%2Fi c:collection1 s:shard1  
x:collection1_shard1_replica_t25] o.a.s.c.SolrCore 
[collection1_shard1_replica_t25] Registered new searcher 
Searcher@1eac607b[collection1_shard1_replica_t25] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3520510 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.c.ZkController 
Core needs to recover:collection1_shard1_replica_t25
   [junit4]   2> 3520510 INFO  
(updateExecutor-3944-thread-1-processing-n:127.0.0.1:52148_pik%2Fi 
x:collection1_shard1_replica_t25 s:shard1 c:collection1) 
[n:127.0.0.1:52148_pik%2Fi c:collection1 s:shard1 r:core_node26 
x:collection1_shard1_replica_t25] o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 3520511 INFO  (qtp1246037770-26384) [n:127.0.0.1:52148_pik%2Fi 
c:collection1 s:shard1  x:collection1_shard1_replica_t25] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t25&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=1198
   [junit4]   2> 3520511 INFO  
(recoveryExecutor-3945-thread-1-processing-n:127.0.0.1:52148_pik%2Fi 
x:collection1_shard1_replica_t25 s:shard1 c:collection1 r:core_node26) 
[n:127.0.0.1:52148_pik%2Fi c:collection1 s:shard1 r:core_node26 
x:collection1_shard1_replica_t25] o.a.s.c.RecoveryStrategy Starting recovery 
process. recoveringAfterStartup=true
   [junit4]   2> 3520511 INFO  
(recoveryExecutor-3945-thread-1-processing-n:127.0.0.1:52148_pik%2Fi 
x:collection1_shard1_replica_t25 s:shard1 c:collection1 r:core_node26) 
[n:127.0.0.1:52148_pik%2Fi c:collection1 s:shard1 r:core_node26 
x:collection1_shard1_replica_t25] o.a.s.c.RecoveryStrategy ###### 
startupVersions=[[]]
   [junit4]   2> 3520511 INFO  
(recoveryExecutor-3945-thread-1-processing-n:127.0.0.1:52148_pik%2Fi 
x:collection1_shard1_replica_t25 s:shard1 c:collection1 r:core_node26) [n:127.0.

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

22 INFO  (coreCloseExecutor-11436-thread-1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@24b92497
   [junit4]   2> 3714022 INFO  (coreCloseExecutor-11436-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=616113303
   [junit4]   2> 3714022 INFO  (coreCloseExecutor-11436-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7230a420: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@174cc505
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_B596B7031EB8B8B9-001
   [junit4]   2> Sep 25, 2017 5:36:04 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(Lucene70), 
sim=RandomSimilarity(queryNorm=false): {}, locale=hi-IN, 
timezone=Indian/Mauritius
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=1,free=307196480,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestConfigSets, 
ZkNodePropsTest, SystemInfoHandlerTest, ConnectionReuseTest, 
SpatialRPTFieldTypeTest, TestRealTimeGet, TestHdfsCloudBackupRestore, 
TestReRankQParserPlugin, TestMacros, StatsComponentTest, 
DistributedFacetPivotLargeTest, TestFieldSortValues, 
HdfsRestartWhileUpdatingTest, CoreAdminCreateDiscoverTest, AtomicUpdatesTest, 
TestSolrJ, ConfigureRecoveryStrategyTest, TestDFISimilarityFactory, 
TestPayloadScoreQParserPlugin, StatsReloadRaceTest, SortSpecParsingTest, 
RestartWhileUpdatingTest, TestSolrIndexConfig, TestSolr4Spatial, 
MetricUtilsTest, TestReloadAndDeleteDocs, TestAnalyzeInfixSuggestions, 
SearchHandlerTest, DirectoryFactoryTest, TestSystemIdResolver, 
TestDocumentBuilder, HttpSolrCallGetCoreTest, ConjunctionSolrSpellCheckerTest, 
AnalyticsMergeStrategyTest, TestCloudManagedSchema, CacheHeaderTest, 
BadCopyFieldTest, TolerantUpdateProcessorTest, TestCoreAdminApis, 
TestCollationField, TestLRUStatsCache, TermVectorComponentTest, 
TestLuceneMatchVersion, TestFileDictionaryLookup, TestSolrXml, 
TestConfigSetsAPI, SolrSlf4jReporterTest, DeleteReplicaTest, 
DistributedQueueTest, TestReplicaProperties, TestPullReplicaErrorHandling, 
TestJsonRequest, FullHLLTest, AssignTest, TestManagedSchemaThreadSafety, 
TestPostingsSolrHighlighter, UpdateParamsTest, TestCollapseQParserPlugin, 
DistributedIntervalFacetingTest, TestCoreDiscovery, SpellingQueryConverterTest, 
TestSegmentSorting, TestConfig, TestXmlQParser, SpatialHeatmapFacetsTest, 
HdfsChaosMonkeyNothingIsSafeTest, BasicFunctionalityTest, 
TestMaxScoreQueryParser, TestInitQParser, TestSubQueryTransformerCrossCore, 
SolrIndexMetricsTest, TestDistributedGrouping, MultiThreadedOCPTest, 
TestImplicitCoreProperties, TestJsonFacetRefinement, 
SolrCoreCheckLockOnStartupTest, PolyFieldTest, HealthCheckHandlerTest, 
BadComponentTest, TestEmbeddedSolrServerSchemaAPI, SmileWriterTest, 
CollectionsAPIDistributedZkTest, TestCollectionAPI, TestCustomDocTransformer, 
TestStressCloudBlindAtomicUpdates, TestReload, HdfsRecoverLeaseTest, 
CloneFieldUpdateProcessorFactoryTest, HLLUtilTest, SSLMigrationTest, 
TestOverriddenPrefixQueryForCustomFieldType, DeleteStatusTest, 
TestSolrCLIRunExample, FullSolrCloudDistribCmdsTest, SolrGangliaReporterTest, 
DocumentBuilderTest, DistributedQueryElevationComponentTest, 
TestMultiWordSynonyms, SolrJmxReporterCloudTest, ChaosMonkeySafeLeaderTest, 
CollectionsAPIAsyncDistributedZkTest, DebugComponentTest, TestValueSourceCache, 
SampleTest, TestUseDocValuesAsStored, TestLeaderElectionZkExpiry, 
BasicDistributedZk2Test, DocumentAnalysisRequestHandlerTest, 
TestCloudPseudoReturnFields, SuggestComponentTest, 
ClassificationUpdateProcessorIntegrationTest, ConnectionManagerTest, 
TestUninvertingReader, QueryEqualityTest, TestConfigReload, TestFieldResource, 
TestCharFilters, TestSolrConfigHandlerConcurrent, TestSolr4Spatial2, 
CdcrRequestHandlerTest, TestSQLHandlerNonCloud, MBeansHandlerTest, 
TestQueryUtils, TestDocSet, TestRawResponseWriter, TestJoin, SolrXmlInZkTest, 
TestExactSharedStatsCache, PreAnalyzedFieldManagedSchemaCloudTest, 
TestSimpleTrackingShardHandler, TestStressUserVersions, SuggesterFSTTest, 
TestGeoJSONResponseWriter, TestFieldCacheReopen, PreAnalyzedFieldTest, 
TestTrie, LukeRequestHandlerTest, TestPivotHelperCode, TestCrossCoreJoin, 
TestManagedSynonymFilterFactory, PingRequestHandlerTest, DOMUtilTest, 
SynonymTokenizerTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestInitParams, 
TestSimpleQParserPlugin, CollectionStateFormat2Test, 
CoreMergeIndexesAdminHandlerTest, TestRequestStatusCollectionAPI, 
TestFastOutputStream, TestComponentsName, DirectSolrSpellCheckerTest, 
FileUtilsTest, UninvertDocValuesMergePolicyTest, 
DistributedFacetPivotWhiteBoxTest, AliasIntegrationTest, 
TestFieldCacheWithThreads, TestRuleBasedAuthorizationPlugin, 
TestCopyFieldCollectionResource, CursorMarkTest, TestRTGBase, ZkSolrClientTest, 
ReplicaListTransformerTest, TestNumericTokenStream, AnalysisErrorHandlingTest, 
EnumFieldTest, ConvertedLegacyTest, TestDelegationWithHadoopAuth, 
TemplateUpdateProcessorTest, TestFiltering, UpdateLogTest, 
TestGraphTermsQParserPlugin, TestCollectionAPIs, 
TestEmbeddedSolrServerAdminHandler, TestSolrDeletionPolicy1, 
TermVectorComponentDistributedTest, TestLRUCache, TestSolrQueryParser, 
TestPartialUpdateDeduplication, BasicDistributedZkTest, TestRandomFaceting, 
TestZkChroot, ShardRoutingCustomTest, AutoCommitTest, 
DistributedSpellCheckComponentTest, TestRangeQuery, SpellCheckComponentTest, 
BadIndexSchemaTest, TestFunctionQuery, DirectUpdateHandlerTest, TestBadConfig, 
TestIndexSearcher, ShowFileRequestHandlerTest, SimplePostToolTest, 
SpellCheckCollatorTest, SolrRequestParserTest, TestFoldingMultitermQuery, 
SuggesterTSTTest, TestStressLucene, TestCSVLoader, 
SchemaVersionSpecificBehaviorTest, TestUpdate, TestAtomicUpdateErrorCases, 
DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, 
DistanceFunctionTest, IndexBasedSpellCheckerTest, 
TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TermsComponentTest, 
TestJmxIntegration, QueryParsingTest, JsonLoaderTest, CSVRequestHandlerTest, 
TestQuerySenderNoQuery, SolrIndexConfigTest, TestSolrDeletionPolicy2, 
TestSearchPerf, TestElisionMultitermQuery, ExternalFileFieldSortTest, 
TestSolrCoreProperties, SpellPossibilityIteratorTest, TestXIncludeConfig, 
TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, 
TestBM25SimilarityFactory, ScriptEngineTest, URLClassifyProcessorTest, 
TestSuggestSpellingConverter, SliceStateTest, DistributedMLTComponentTest, 
SolrTestCaseJ4Test, TestEmbeddedSolrServerConstructors, ActionThrottleTest, 
AsyncCallRequestStatusResponseTest, BaseCdcrDistributedZkTest, 
CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, 
CdcrVersionReplicationTest, ChaosMonkeySafeLeaderWithPullReplicasTest, 
CleanupOldIndexTest, CollectionReloadTest, DeleteInactiveReplicaTest, 
DeleteLastCustomShardedReplicaTest, DeleteNodeTest, 
DistribDocExpirationUpdateProcessorTest, DocValuesNotIndexedTest, 
HttpPartitionTest, RemoteQueryErrorTest, ReplaceNodeTest, 
ReplicationFactorTest, ShardSplitTest, TlogReplayBufferedWhileIndexingTest, 
VMParamsZkACLAndCredentialsProvidersTest, TestPolicyCloud, 
BlobRepositoryCloudTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, 
TestBackupRepositoryFactory, TestConfigSetImmutable, TestCustomStream, 
TestInfoStreamLogging, TestShardHandlerFactory, TestSimpleTextCodec, 
TestSolrConfigHandler, SearchHandlerTest, TestReplicationHandlerBackup, 
TestRestoreCore, TestSystemCollAutoCreate, V2ApiIntegrationTest, 
PropertiesRequestHandlerTest, DistributedFacetPivotLongTailTest, 
DistributedFacetPivotSmallTest, FacetPivotSmallTest, 
SuggestComponentContextFilterQueryTest, 
TestDistributedStatsComponentCardinality, SubstringBytesRefFilterTest, 
TestStreamBody, JSONWriterTest, TestJavabinTupleStreamParser, 
TestRawTransformer, TestRetrieveFieldsOptimizer, TestSolrQueryResponse, 
TestChildDocTransformer, TestManagedResource, TestManagedResourceStorage, 
TestRestManager, TestDynamicFieldCollectionResource, TestDynamicFieldResource, 
TestFieldCollectionResource, TestFieldTypeResource, TestSchemaNameResource, 
TestSchemaResource, TestSchemaSimilarityResource, TestSchemaVersionResource, 
TestUniqueKeyFieldResource, ChangedSchemaMergeTest, DateRangeFieldTest, 
ManagedSchemaRoundRobinCloudTest, TestCollationFieldDocValues, 
TestManagedSchema, TestSchemaManager, TestSchemalessBufferedUpdates, 
MergeStrategyTest, RankQueryTest, TestAddFieldRealTimeGet, 
TestComplexPhraseLeadingWildcard, TestComplexPhraseQParserPlugin, 
TestHashQParserPlugin, TestLegacyNumericRangeQueryBuilder, TestMissingGroups, 
TestNoOpRegenerator, TestPayloadCheckQParserPlugin, TestQueryWrapperFilter, 
TestRandomCollapseQParserPlugin, TestSolrFieldCacheBean, TestStandardQParsers, 
TestTrieFacet, TestMinMaxOnMultiValuedField, TestOrdValues, 
TestSortByMinMaxFunction, BJQParserTest, BlockJoinFacetDistribTest, 
BlockJoinFacetSimpleTest, TestCloudNestedDocsSort, TestScoreJoinQPNoScore, 
TestScoreJoinQPScore, SimpleMLTQParserTest, TestClassicSimilarityFactory, 
TestNonDefinedSimilarityFactory, TestPerFieldSimilarityWithDefaultOverride, 
TestDefaultStatsCache, BasicAuthIntegrationTest, BasicAuthStandaloneTest, 
PKIAuthenticationIntegrationTest, TestAuthorizationFramework, 
TestBlendedInfixSuggestions, TestFreeTextSuggestions, BlockCacheTest, 
BufferStoreTest, HdfsDirectoryTest, HdfsLockFactoryTest, TestDocTermOrds, 
TestFieldCacheSortRandom, TestLegacyFieldCache, TestNumericTerms32, 
TestNumericTerms64, TestInPlaceUpdatesDistrib]
   [junit4] Completed [733/733 (1!)] on J1 in 203.22s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 46618 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to