[JENKINS] Lucene-Solr-NightlyTests-8.0 - Build # 20 - Failure

Apache Jenkins Server Thu, 14 Mar 2019 22:18:39 -0700

Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-8.0/20/


2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.cdcr.CdcrBootstrapTest

Error Message:
ObjectTracker found 1 object(s) that were not released!!! [RawDirectoryWrapper] 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: 
org.apache.lucene.store.RawDirectoryWrapper  at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
  at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
  at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)  
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346) 
 at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:424) 
 at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:768)
  at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:723)
  at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
  at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 
 at java.lang.Thread.run(Thread.java:748)   expected null, but 
was:<ObjectTracker found 1 object(s) that were not released!!! 
[RawDirectoryWrapper] 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: 
org.apache.lucene.store.RawDirectoryWrapper  at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
  at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
  at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)  
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346) 
 at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:424) 
 at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:768)
  at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:723)
  at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
  at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 
 at java.lang.Thread.run(Thread.java:748)  >

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not 
released!!! [RawDirectoryWrapper]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: 
org.apache.lucene.store.RawDirectoryWrapper
        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)
        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:424)
        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:768)
        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:723)
        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)

 expected null, but was:<ObjectTracker found 1 object(s) that were not 
released!!! [RawDirectoryWrapper]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: 
org.apache.lucene.store.RawDirectoryWrapper
        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)
        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:424)
        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:768)
        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:723)
        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)

>
        at __randomizedtesting.SeedInfo.seed([4EC2A3320DFA5CD6]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotNull(Assert.java:755)
        at org.junit.Assert.assertNull(Assert.java:737)
        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:322)
        at sun.reflect.GeneratedMethodAccessor46.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:901)
        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)


FAILED:  
org.apache.solr.uninverting.TestDocTermOrdsUninvertLimit.testTriggerUnInvertLimit

Error Message:
Java heap space

Stack Trace:
java.lang.OutOfMemoryError: Java heap space
        at 
__randomizedtesting.SeedInfo.seed([4EC2A3320DFA5CD6:7D708BF6004D8661]:0)
        at 
org.apache.lucene.codecs.memory.DirectPostingsFormat$DirectField.<init>(DirectPostingsFormat.java:425)
        at 
org.apache.lucene.codecs.memory.DirectPostingsFormat$DirectFields.<init>(DirectPostingsFormat.java:132)
        at 
org.apache.lucene.codecs.memory.DirectPostingsFormat.fieldsProducer(DirectPostingsFormat.java:116)
        at 
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:288)
        at 
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:368)
        at 
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:113)
        at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:83)
        at org.apache.lucene.index.CheckIndex.checkIndex(CheckIndex.java:676)
        at org.apache.lucene.util.TestUtil.checkIndex(TestUtil.java:279)
        at 
org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:862)
        at 
org.apache.solr.uninverting.TestDocTermOrdsUninvertLimit.testTriggerUnInvertLimit(TestDocTermOrdsUninvertLimit.java:86)
        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:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)




Build Log:
[...truncated 13666 lines...]
   [junit4] Suite: org.apache.solr.cloud.cdcr.CdcrBootstrapTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/init-core-data-001
   [junit4]   2> 447582 WARN  
(SUITE-CdcrBootstrapTest-seed#[4EC2A3320DFA5CD6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
   [junit4]   2> 447582 INFO  
(SUITE-CdcrBootstrapTest-seed#[4EC2A3320DFA5CD6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 447616 INFO  
(SUITE-CdcrBootstrapTest-seed#[4EC2A3320DFA5CD6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 447616 INFO  
(SUITE-CdcrBootstrapTest-seed#[4EC2A3320DFA5CD6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4] IGNOR/A 0.01s J2 | 
CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J2 | 
CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster
   [junit4]    > Assumption #1: 'awaitsfix' test group is disabled 
(@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4]   2> 447624 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testBootstrapWithSourceCluster
   [junit4]   2> 447624 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/cdcr-target-001
   [junit4]   2> 447625 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 447632 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 447632 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 447732 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer start zk server on port:33376
   [junit4]   2> 447732 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33376
   [junit4]   2> 447732 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33376
   [junit4]   2> 447792 INFO  (zkConnectionManagerCallback-2641-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 447824 INFO  (zkConnectionManagerCallback-2643-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 447837 INFO  (zkConnectionManagerCallback-2645-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 447851 WARN  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 447860 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 447860 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 447860 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 447873 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 447873 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 447874 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 447874 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2592ca99{/solr,null,AVAILABLE}
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@5aca4b45{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:35743}
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.s.Server Started @447990ms
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35743}
   [junit4]   2> 447876 ERROR (jetty-launcher-2646-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 447876 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-15T04:02:37.346Z
   [junit4]   2> 447888 INFO  (zkConnectionManagerCallback-2648-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 447889 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 448391 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 448393 WARN  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@4a12b22b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 448413 WARN  (jetty-launcher-2646-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@219724c2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 448414 INFO  (jetty-launcher-2646-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33376/solr
   [junit4]   2> 448436 INFO  (zkConnectionManagerCallback-2654-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 448476 INFO  (zkConnectionManagerCallback-2656-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 448590 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:35743_solr
   [junit4]   2> 448591 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.c.Overseer Overseer 
(id=72959193407619077-127.0.0.1:35743_solr-n_0000000000) starting
   [junit4]   2> 448608 INFO  (zkConnectionManagerCallback-2663-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 448611 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:33376/solr ready
   [junit4]   2> 448616 INFO  
(OverseerStateUpdate-72959193407619077-127.0.0.1:35743_solr-n_0000000000) 
[n:127.0.0.1:35743_solr    ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:35743_solr
   [junit4]   2> 448624 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35743_solr
   [junit4]   2> 448671 INFO  (zkCallback-2655-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 448712 INFO  (zkCallback-2662-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 448756 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 448858 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35743.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 448887 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35743.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 448887 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35743.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 448900 INFO  (jetty-launcher-2646-thread-1) 
[n:127.0.0.1:35743_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/cdcr-target-001/node1/.
   [junit4]   2> 448994 INFO  (zkConnectionManagerCallback-2669-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 448996 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 448997 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33376/solr 
ready
   [junit4]   1> Target zkHost = 127.0.0.1:33376/solr
   [junit4]   2> 448998 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/cdcr-source-001
   [junit4]   2> 448998 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 448998 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 448998 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 449098 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer start zk server on port:37141
   [junit4]   2> 449098 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37141
   [junit4]   2> 449099 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37141
   [junit4]   2> 449272 INFO  (zkConnectionManagerCallback-2671-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449278 INFO  (zkConnectionManagerCallback-2673-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449280 INFO  (zkConnectionManagerCallback-2675-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449283 WARN  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 449296 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 449296 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 449296 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 449305 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 449305 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 449305 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 449306 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@789922ee{/solr,null,AVAILABLE}
   [junit4]   2> 449307 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@73a361bd{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:43379}
   [junit4]   2> 449307 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.s.Server Started @449421ms
   [junit4]   2> 449307 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43379}
   [junit4]   2> 449307 ERROR (jetty-launcher-2676-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 449307 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 449307 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 449308 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 449308 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 449308 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-15T04:02:38.778Z
   [junit4]   2> 449310 INFO  (zkConnectionManagerCallback-2678-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449311 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 449750 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 449751 WARN  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1663909[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 449755 WARN  (jetty-launcher-2676-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@24fbbbbb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 449757 INFO  (jetty-launcher-2676-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37141/solr
   [junit4]   2> 449758 INFO  (zkConnectionManagerCallback-2684-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449774 INFO  (zkConnectionManagerCallback-2686-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449882 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:43379_solr
   [junit4]   2> 449883 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.Overseer Overseer 
(id=72959193495896069-127.0.0.1:43379_solr-n_0000000000) starting
   [junit4]   2> 449924 INFO  (zkConnectionManagerCallback-2693-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 449928 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:37141/solr ready
   [junit4]   2> 449929 INFO  
(OverseerStateUpdate-72959193495896069-127.0.0.1:43379_solr-n_0000000000) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:43379_solr
   [junit4]   2> 449929 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43379_solr
   [junit4]   2> 449940 INFO  (zkCallback-2692-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 449941 INFO  (zkCallback-2685-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 450047 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 450238 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43379.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 450251 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43379.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 450251 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43379.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 450255 INFO  (jetty-launcher-2676-thread-1) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/cdcr-source-001/node1/.
   [junit4]   2> 450355 INFO  (zkConnectionManagerCallback-2699-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 450369 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 450370 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[4EC2A3320DFA5CD6]) 
[    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37141/solr 
ready
   [junit4]   2> 450378 INFO  (zkConnectionManagerCallback-2701-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 450405 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=cdcr-source&name=cdcr-source&nrtReplicas=1&action=CREATE&numShards=1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 450420 INFO  
(OverseerThreadFactory-2246-thread-1-processing-n:127.0.0.1:43379_solr) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
cdcr-source
   [junit4]   2> 450541 INFO  
(OverseerStateUpdate-72959193495896069-127.0.0.1:43379_solr-n_0000000000) 
[n:127.0.0.1:43379_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"cdcr-source",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"cdcr-source_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43379/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 450754 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr    
x:cdcr-source_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&collection=cdcr-source&version=2&replicaType=NRT&coreNodeName=core_node2&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin
   [junit4]   2> 450754 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr    
x:cdcr-source_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault 
Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 451794 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 451802 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 451807 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.IndexSchema [cdcr-source_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 451810 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 451816 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/cdcr-source/managed-schema
   [junit4]   2> 451828 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/cdcr-source/schema.xml to 
/configs/cdcr-source/schema.xml.bak
   [junit4]   2> 451828 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'cdcr-source_shard1_replica_n1' using 
configuration from collection cdcr-source, trusted=true
   [junit4]   2> 451829 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43379.solr.core.cdcr-source.shard1.replica_n1' (registry 
'solr.core.cdcr-source.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d9348f5
   [junit4]   2> 451830 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.SolrCore [[cdcr-source_shard1_replica_n1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/cdcr-source-001/node1/cdcr-source_shard1_replica_n1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrBootstrapTest_4EC2A3320DFA5CD6-001/cdcr-source-001/node1/./cdcr-source_shard1_replica_n1/data/]
   [junit4]   2> 452028 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 452028 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 452031 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 452031 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 452034 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4178f0c[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 452036 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/cdcr-source
   [junit4]   2> 452036 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/cdcr-source
   [junit4]   2> 452036 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/cdcr-source/managed-schema
   [junit4]   2> 452037 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 452037 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 452054 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.h.CdcrBufferStateManager Created znode 
/collections/cdcr-source/cdcr/state/buffer
   [junit4]   2> 452061 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.h.CdcrProcessStateManager Created znode 
/collections/cdcr-source/cdcr/state/process
   [junit4]   2> 452129 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1628042755151233024
   [junit4]   2> 452142 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@4178f0c[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 452146 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/cdcr-source/terms/shard1 to Terms{values={core_node2=0}, version=0}
   [junit4]   2> 452146 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/cdcr-source/leaders/shard1
   [junit4]   2> 452150 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 452150 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 452150 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:43379/solr/cdcr-source_shard1_replica_n1/
   [junit4]   2> 452150 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 452150 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.SyncStrategy http://127.0.0.1:43379/solr/cdcr-source_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 452150 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/cdcr-source/leaders/shard1/leader after winning as 
/collections/cdcr-source/leader_elect/shard1/election/72959193495896069-core_node2-n_0000000000
   [junit4]   2> 452151 INFO  (zkCallback-2685-thread-1) [    ] 
o.a.s.h.CdcrLeaderStateManager Received new leader state @ cdcr-source:shard1
   [junit4]   2> 452154 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:43379/solr/cdcr-source_shard1_replica_n1/ shard1
   [junit4]   2> 452256 INFO  (zkCallback-2685-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/cdcr-source/state.json] for collection [cdcr-source] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 452257 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 452260 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&collection=cdcr-source&version=2&replicaType=NRT&coreNodeName=core_node2&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin}
 status=0 QTime=1506
   [junit4]   2> 452263 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
45 seconds. Check all shard replicas
   [junit4]   2> 452359 INFO  (zkCallback-2685-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/cdcr-source/state.json] for collection [cdcr-source] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 452359 INFO  (zkCallback-2685-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/cdcr-source/state.json] for collection [cdcr-source] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 452360 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=cdcr-source&name=cdcr-source&nrtReplicas=1&action=CREATE&numShards=1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2}
 status=0 QTime=1955
   [junit4]   2> 452422 INFO  
(OverseerCollectionConfigSetProcessor-72959193495896069-127.0.0.1:43379_solr-n_0000000000)
 [n:127.0.0.1:43379_solr    ] 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> 452447 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/cdcr-source/terms/shard1 to Terms{values={core_node2=1}, version=1}
   [junit4]   2> 452448 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=70
   [junit4]   2> 452478 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042755517186048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 452478 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042755517186048
   [junit4]   2> 452647 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4459f0[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 452649 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@4459f0[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}])))}
   [junit4]   2> 452650 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 452650 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=173
   [junit4]   2> 452701 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=49
   [junit4]   2> 452703 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042755753115648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 452704 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042755753115648
   [junit4]   2> 452793 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6cfa1138[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 452794 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 452796 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@6cfa1138[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}])))}
   [junit4]   2> 452796 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=94
   [junit4]   2> 452831 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=32
   [junit4]   2> 452833 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042755889430528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 452833 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042755889430528
   [junit4]   2> 452866 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@783a8d86[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 452868 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@783a8d86[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}])))}
   [junit4]   2> 452869 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 452869 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=37
   [junit4]   2> 452900 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=28
   [junit4]   2> 452912 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042755972268032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 452913 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042755972268032
   [junit4]   2> 452955 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@58aa4239[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 452957 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@58aa4239[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}])))}
   [junit4]   2> 452957 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 452957 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=45
   [junit4]   2> 452974 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 452975 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756038328320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 452976 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756038328320
   [junit4]   2> 453021 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7e6eec5[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453023 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@7e6eec5[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}])))}
   [junit4]   2> 453023 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453023 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=48
   [junit4]   2> 453048 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=16
   [junit4]   2> 453050 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756116971520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453050 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756116971520
   [junit4]   2> 453331 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@cb79c15[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453333 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@cb79c15[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}])))}
   [junit4]   2> 453333 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453334 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=284
   [junit4]   2> 453383 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=47
   [junit4]   2> 453389 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756472438784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453389 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756472438784
   [junit4]   2> 453445 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@38621dfd[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453447 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453461 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@38621dfd[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}]) Uninverting(_6(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562871}])))}
   [junit4]   2> 453462 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=73
   [junit4]   2> 453478 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=14
   [junit4]   2> 453480 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756566810624,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453480 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756566810624
   [junit4]   2> 453512 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@ef8abff[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453515 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@ef8abff[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}]) Uninverting(_6(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562871}]) Uninverting(_7(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562955}])))}
   [junit4]   2> 453516 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453516 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=37
   [junit4]   2> 453541 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=23
   [junit4]   2> 453543 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756633919488,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453543 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756633919488
   [junit4]   2> 453598 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@11144638[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453600 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@11144638[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}]) Uninverting(_6(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562871}]) Uninverting(_7(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562955}]) Uninverting(_8(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563019}])))}
   [junit4]   2> 453600 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453601 INFO  (qtp193713016-9013) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=58
   [junit4]   2> 453619 INFO  (qtp193713016-9014) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=16
   [junit4]   2> 453621 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756715708416,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453621 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756715708416
   [junit4]   2> 453678 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6177c4df[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453680 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@6177c4df[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}]) Uninverting(_6(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562871}]) Uninverting(_7(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562955}]) Uninverting(_8(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563019}]) Uninverting(_9(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563097}])))}
   [junit4]   2> 453682 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453682 INFO  (qtp193713016-9012) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=62
   [junit4]   2> 453734 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=50
   [junit4]   2> 453738 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756838391808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453738 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756838391808
   [junit4]   2> 453835 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@19a726d[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453837 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@19a726d[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}]) Uninverting(_6(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562871}]) Uninverting(_7(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562955}]) Uninverting(_8(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563019}]) Uninverting(_9(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563097}]) Uninverting(_a(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563213}])))}
   [junit4]   2> 453840 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 453840 INFO  (qtp193713016-9015) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
 status=0 QTime=105
   [junit4]   2> 453855 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.c.S.Request [cdcr-source_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 453878 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1628042756985192448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 453878 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1b507e3a 
commitCommandVersion:1628042756985192448
   [junit4]   2> 453972 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@27970324[cdcr-source_shard1_replica_n1] main]
   [junit4]   2> 453974 INFO  
(searcherExecutor-2251-thread-1-processing-n:127.0.0.1:43379_solr 
x:cdcr-source_shard1_replica_n1 c:cdcr-source s:shard1 r:core_node2) 
[n:127.0.0.1:43379_solr c:cdcr-source s:shard1 r:core_node2 
x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore 
[cdcr-source_shard1_replica_n1] Registered new searcher 
Searcher@27970324[cdcr-source_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C100:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562052}]) Uninverting(_1(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562194}]) Uninverting(_2(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562313}]) Uninverting(_3(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562396}]) Uninverting(_4(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562451}]) Uninverting(_5(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562528}]) Uninverting(_6(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562871}]) Uninverting(_7(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622562955}]) Uninverting(_8(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563019}]) Uninverting(_9(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563097}]) Uninverting(_a(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563213}]) Uninverting(_b(8.0.0):C100:[diagnostics={os=Linux, 
java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1552622563397}])))}
   [junit4]   2> 453975 INFO  (qtp193713016-9011) [n:127.0.0.1:43379_solr 
c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler

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

llapseTest, AlternateDirectoryTest, LeaderVoteWaitTimeoutTest, 
ZookeeperStatusHandlerTest, SolrTestCaseJ4Test, TestSimpleTrackingShardHandler, 
JavabinLoaderTest, TestAuthenticationFramework, PreAnalyzedUpdateProcessorTest, 
ConfigSetsAPITest, TestSolrCloudWithKerberosAlt, TestSolrIndexConfig, 
CloudMLTQParserTest, Tagger2Test, OutputWriterTest, 
TestSizeLimitedDistributedMap, TestMaxTokenLenTokenizer, 
DocValuesNotIndexedTest, ShardsWhitelistTest, TestFieldCacheSortRandom, 
CdcrBootstrapTest]
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CdcrBootstrapTest 
-Dtests.seed=4EC2A3320DFA5CD6 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/test-data/enwiki.random.lines.txt
 -Dtests.locale=sr -Dtests.timezone=America/Knox_IN -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J2 | CdcrBootstrapTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 
object(s) that were not released!!! [RawDirectoryWrapper]
   [junit4]    > 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: 
org.apache.lucene.store.RawDirectoryWrapper
   [junit4]    >        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]    >        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
   [junit4]    >        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
   [junit4]    >        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)
   [junit4]    >        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:424)
   [junit4]    >        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:768)
   [junit4]    >        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:723)
   [junit4]    >        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    >  expected null, but was:<ObjectTracker found 1 object(s) that 
were not released!!! [RawDirectoryWrapper]
   [junit4]    > 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: 
org.apache.lucene.store.RawDirectoryWrapper
   [junit4]    >        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]    >        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
   [junit4]    >        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
   [junit4]    >        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:346)
   [junit4]    >        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:424)
   [junit4]    >        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:768)
   [junit4]    >        at 
org.apache.solr.handler.CdcrRequestHandler$BootstrapCallable.call(CdcrRequestHandler.java:723)
   [junit4]    >        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > >
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4EC2A3320DFA5CD6]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:322)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [145/839 (1!)] on J2 in 92.00s, 3 tests, 1 failure, 2 
skipped <<< FAILURES!

[...truncated 118 lines...]
   [junit4] Suite: org.apache.solr.uninverting.TestDocTermOrdsUninvertLimit
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDocTermOrdsUninvertLimit -Dtests.method=testTriggerUnInvertLimit 
-Dtests.seed=4EC2A3320DFA5CD6 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/test-data/enwiki.random.lines.txt
 -Dtests.locale=ar-KW -Dtests.timezone=America/Boa_Vista -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   99.8s J1 | 
TestDocTermOrdsUninvertLimit.testTriggerUnInvertLimit <<<
   [junit4]    > Throwable #1: java.lang.OutOfMemoryError: Java heap space
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4EC2A3320DFA5CD6:7D708BF6004D8661]:0)
   [junit4]    >        at 
org.apache.lucene.codecs.memory.DirectPostingsFormat$DirectField.<init>(DirectPostingsFormat.java:425)
   [junit4]    >        at 
org.apache.lucene.codecs.memory.DirectPostingsFormat$DirectFields.<init>(DirectPostingsFormat.java:132)
   [junit4]    >        at 
org.apache.lucene.codecs.memory.DirectPostingsFormat.fieldsProducer(DirectPostingsFormat.java:116)
   [junit4]    >        at 
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:288)
   [junit4]    >        at 
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:368)
   [junit4]    >        at 
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:113)
   [junit4]    >        at 
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:83)
   [junit4]    >        at 
org.apache.lucene.index.CheckIndex.checkIndex(CheckIndex.java:676)
   [junit4]    >        at 
org.apache.lucene.util.TestUtil.checkIndex(TestUtil.java:279)
   [junit4]    >        at 
org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:862)
   [junit4]    >        at 
org.apache.solr.uninverting.TestDocTermOrdsUninvertLimit.testTriggerUnInvertLimit(TestDocTermOrdsUninvertLimit.java:86)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): 
{field=PostingsFormat(name=Direct)}, docValues:{}, maxPointsInLeafNode=576, 
maxMBSortInHeap=5.879346143370903, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@26958b1b),
 locale=ar-KW, timezone=America/Boa_Vista
   [junit4]   2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 
1.8.0_191 (64-bit)/cpus=4,threads=1,free=413679432,total=455606272
   [junit4]   2> NOTE: All tests run in this JVM: [TestSearcherReuse, 
TestApiFramework, TestCoreAdminApis, SchemaApiFailureTest, 
TaggingAttributeTest, HdfsDirectoryTest, ExternalFileFieldSortTest, 
IndexSizeTriggerTest, DateFieldTest, CloudExitableDirectoryReaderTest, 
ResponseHeaderTest, TestValueSourceCache, TestSchemaVersionResource, 
TestManagedSchema, TestShortCircuitedRequests, TestBM25SimilarityFactory, 
OpenExchangeRatesOrgProviderTest, BlockCacheTest, 
UninvertDocValuesMergePolicyTest, TestHashPartitioner, BJQParserTest, 
TestHttpShardHandlerFactory, TestSimpleTextCodec, TestConfigSetsAPI, 
TestCloudDeleteByQuery, CoreAdminOperationTest, RulesTest, 
TlogReplayBufferedWhileIndexingTest, TestSchemaNameResource, 
ExecutePlanActionTest, BasicDistributedZk2Test, TestSimPolicyCloud, 
TestCollectionsAPIViaSolrCloudCluster, BasicFunctionalityTest, 
TestEmbeddedSolrServerConstructors, TestDynamicURP, 
DefaultValueUpdateProcessorTest, TestOmitPositions, TestFieldCacheWithThreads, 
CdcrBidirectionalTest, DeleteShardTest, HdfsNNFailoverTest, TestRestManager, 
HttpPartitionTest, TestLuceneMatchVersion, 
ClassificationUpdateProcessorIntegrationTest, TokenizerChainTest, 
ZkStateWriterTest, FullSolrCloudDistribCmdsTest, SpellCheckCollatorTest, 
TestCloudPivotFacet, SpatialRPTFieldTypeTest, TestCollationField, 
ExitableDirectoryReaderTest, TestXIncludeConfig, DOMUtilTest, 
ReplaceNodeNoTargetTest, MinimalSchemaTest, TestSimExecutePlanAction, 
TestDocTermOrdsUninvertLimit]
   [junit4] Completed [183/839 (2!)] on J1 in 99.90s, 1 test, 1 error <<< 
FAILURES!

[...truncated 2237 lines...]
   [junit4] JVM J1: stdout was not empty, see: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/solr/build/solr-core/test/temp/junit4-J1-20190315_035509_2726606370985192163549.sysout
   [junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: Java heap space
   [junit4] Dumping heap to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/heapdumps/java_pid2728.hprof
 ...
   [junit4] Heap dump file created [479304380 bytes in 2.852 secs]
   [junit4] <<< JVM J1: EOF ----

[...truncated 8947 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/build.xml:651:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.0/checkout/build.xml:585:
 Some of the tests produced a heap dump, but did not fail. Maybe a suppressed 
OutOfMemoryError? Dumps created:
* java_pid2728.hprof

Total time: 293 minutes 50 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

[JENKINS] Lucene-Solr-NightlyTests-8.0 - Build # 20 - Failure

Reply via email to