You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/02/08 15:21:52 UTC

[JENKINS-Experimental-GC] Lucene-Solr-master-Linux (64bit/jdk-11.0.6) - Build # 25638 - Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25638/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC

1 tests failed.
FAILED:  org.apache.solr.index.UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues

Error Message:
expected:<[10]> but was:<[88]>

Stack Trace:
org.junit.ComparisonFailure: expected:<[10]> but was:<[88]>
	at __randomizedtesting.SeedInfo.seed([C1E092D1FF33741:59F98FEE561477B5]:0)
	at org.junit.Assert.assertEquals(Assert.java:115)
	at org.junit.Assert.assertEquals(Assert.java:144)
	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.lambda$testIndexAndAddDocValues$2(UninvertDocValuesMergePolicyTest.java:143)
	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.withNewRawReader(UninvertDocValuesMergePolicyTest.java:233)
	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues(UninvertDocValuesMergePolicyTest.java:118)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
	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 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:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
	at java.base/java.lang.Thread.run(Thread.java:834)




Build Log:
[...truncated 14380 lines...]
   [junit4] Suite: org.apache.solr.index.UninvertDocValuesMergePolicyTest
   [junit4]   2> 797044 INFO  (SUITE-UninvertDocValuesMergePolicyTest-seed#[C1E092D1FF33741]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 797045 INFO  (SUITE-UninvertDocValuesMergePolicyTest-seed#[C1E092D1FF33741]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.index.UninvertDocValuesMergePolicyTest_C1E092D1FF33741-001/data-dir-73-001
   [junit4]   2> 797045 WARN  (SUITE-UninvertDocValuesMergePolicyTest-seed#[C1E092D1FF33741]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 797045 INFO  (SUITE-UninvertDocValuesMergePolicyTest-seed#[C1E092D1FF33741]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 797046 INFO  (SUITE-UninvertDocValuesMergePolicyTest-seed#[C1E092D1FF33741]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 797066 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testNonIndexedFieldDoesNonFail
   [junit4]   2> 797067 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 797067 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 797082 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 797104 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.s.IndexSchema [null] Schema name=schema-docValues
   [junit4]   2> 797113 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.s.IndexSchema Loaded schema schema-docValues/1.6 with uniqueid field id
   [junit4]   2> 797363 WARN  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr].  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
   [junit4]   2> 	at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdir(File.java:1325) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdirs(File.java:1357) ~[?:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:619) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:817) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:807) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:668) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.before(UninvertDocValuesMergePolicyTest.java:64) ~[test/:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:834) [?:?]
   [junit4]   2> 797375 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 797378 WARN  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@14d6a551[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797378 WARN  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@14d6a551[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797400 WARN  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3f3c770f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797400 WARN  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3f3c770f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797401 WARN  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 797435 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 797435 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 797447 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797453 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797453 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797471 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 797482 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 797501 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=schema-docValues
   [junit4]   2> 797512 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema schema-docValues/1.6 with uniqueid field id
   [junit4]   2> 797512 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 797512 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797513 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.index.UninvertDocValuesMergePolicyTest_C1E092D1FF33741-001/data-dir-73-001/]
   [junit4]   2> 797552 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 797552 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 797553 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41dbd6a1[collection1] main]
   [junit4]   2> 797553 WARN  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 797553 INFO  (coreLoadExecutor-4200-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 797554 INFO  (searcherExecutor-4201-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41dbd6a1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 797554 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 797556 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 797557 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 797557 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@d1c4661 commitCommandVersion:0
   [junit4]   2> 797559 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@54467eb0[collection1] main]
   [junit4]   2> 797559 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 797560 INFO  (searcherExecutor-4201-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@54467eb0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812381, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 797560 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 3
   [junit4]   2> 797561 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 797561 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 797561 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@d1c4661 commitCommandVersion:0
   [junit4]   2> 797563 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@271d7f32[collection1] main]
   [junit4]   2> 797564 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 797565 INFO  (searcherExecutor-4201-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@271d7f32[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812381, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812385, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 797584 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 22
   [junit4]   2> 797584 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 797607 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@d1c4661 commitCommandVersion:0
   [junit4]   2> 797608 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11a771f1[collection1] main]
   [junit4]   2> 797609 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 797609 INFO  (searcherExecutor-4201-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11a771f1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(9.0.0):C2:[diagnostics={mergeMaxNumSegments=1, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0, timestamp=1581173812418, os=Linux, java.runtime.version=11.0.6+10, mergeFactor=2, os.arch=amd64, source=merge, java.vendor=AdoptOpenJDK, os.version=5.3.0-26-generic}])))}
   [junit4]   2> 797610 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{optimize=} 0 25
   [junit4]   2> 797611 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 797611 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1296614324
   [junit4]   2> 797616 INFO  (coreCloseExecutor-4205-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3ab4bd9c
   [junit4]   2> 797616 INFO  (coreCloseExecutor-4205-thread-1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@3ab4bd9c
   [junit4]   2> 797616 INFO  (coreCloseExecutor-4205-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@435191cb: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@464f258
   [junit4]   2> 797638 INFO  (coreCloseExecutor-4205-thread-1) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 797650 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 797650 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55c03db3: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4626b14
   [junit4]   2> 797656 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 797656 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67b3a719: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41097e1f
   [junit4]   2> 797659 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 797659 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4433574b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@66a1fe75
   [junit4]   2> 797663 INFO  (TEST-UninvertDocValuesMergePolicyTest.testNonIndexedFieldDoesNonFail-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testNonIndexedFieldDoesNonFail
   [junit4]   2> 797671 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testIndexAndAddDocValues
   [junit4]   2> 797672 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 797672 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 797733 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 797749 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.index.UninvertDocValuesMergePolicyTest_C1E092D1FF33741-001/data-dir-74-001
   [junit4]   2> 797751 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.IndexSchema [null] Schema name=schema-docValues
   [junit4]   2> 797754 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.IndexSchema Loaded schema schema-docValues/1.6 with uniqueid field id
   [junit4]   2> 797838 WARN  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr].  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
   [junit4]   2> 	at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdir(File.java:1325) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdirs(File.java:1357) ~[?:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:619) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:817) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:807) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:668) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.before(UninvertDocValuesMergePolicyTest.java:64) ~[test/:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:834) [?:?]
   [junit4]   2> 797840 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 797841 WARN  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@77b47e83[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797841 WARN  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@77b47e83[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797855 WARN  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3f896aa4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797855 WARN  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3f896aa4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 797856 WARN  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 797891 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 797891 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 797903 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797910 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797919 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797930 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 797946 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 797967 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=schema-docValues
   [junit4]   2> 797971 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema schema-docValues/1.6 with uniqueid field id
   [junit4]   2> 797971 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 797971 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c38be38
   [junit4]   2> 797971 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.index.UninvertDocValuesMergePolicyTest_C1E092D1FF33741-001/data-dir-74-001/]
   [junit4]   2> 798001 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 798001 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 798012 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@284c6493[collection1] main]
   [junit4]   2> 798012 WARN  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 798013 INFO  (coreLoadExecutor-4215-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 798013 INFO  (searcherExecutor-4216-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@284c6493[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 798014 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 798015 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 798015 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 798015 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798015 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798018 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5939de04[collection1] main]
   [junit4]   2> 798018 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798023 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5939de04[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798023 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 7
   [junit4]   2> 798024 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 798025 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 798025 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 798025 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798025 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798029 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@798e4424[collection1] main]
   [junit4]   2> 798030 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798033 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@798e4424[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798033 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 7
   [junit4]   2> 798034 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 798035 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 798035 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798035 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798037 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41039257[collection1] main]
   [junit4]   2> 798038 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798039 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41039257[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798039 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 4
   [junit4]   2> 798040 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 798040 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 798041 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 798041 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798041 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798046 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@59789ff9[collection1] main]
   [junit4]   2> 798046 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798047 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@59789ff9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798047 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 5
   [junit4]   2> 798049 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[10]} 0 1
   [junit4]   2> 798049 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[11]} 0 0
   [junit4]   2> 798058 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[12]} 0 9
   [junit4]   2> 798059 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[13]} 0 0
   [junit4]   2> 798060 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798060 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798062 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@319c2dd2[collection1] main]
   [junit4]   2> 798063 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798065 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@319c2dd2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798066 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 6
   [junit4]   2> 798067 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[14]} 0 0
   [junit4]   2> 798067 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798067 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798069 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c494d70[collection1] main]
   [junit4]   2> 798070 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798071 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2c494d70[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798071 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 3
   [junit4]   2> 798072 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[15]} 0 0
   [junit4]   2> 798073 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[16]} 0 0
   [junit4]   2> 798073 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[17]} 0 0
   [junit4]   2> 798073 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[18]} 0 0
   [junit4]   2> 798073 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798073 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798076 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f19e8cf[collection1] main]
   [junit4]   2> 798077 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798078 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6f19e8cf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_6(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812898, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798078 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 4
   [junit4]   2> 798079 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[19]} 0 0
   [junit4]   2> 798079 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[20]} 0 0
   [junit4]   2> 798079 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798079 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798082 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@601127[collection1] main]
   [junit4]   2> 798082 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798083 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@601127[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_6(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812898, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_7(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812903, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798084 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 5
   [junit4]   2> 798091 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[21]} 0 6
   [junit4]   2> 798091 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798091 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798098 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e34045c[collection1] main]
   [junit4]   2> 798098 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798099 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e34045c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_6(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812898, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_7(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812903, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_8(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812919, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798099 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 7
   [junit4]   2> 798100 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[22]} 0 0
   [junit4]   2> 798100 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[23]} 0 0
   [junit4]   2> 798101 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798101 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798109 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c34c3e8[collection1] main]
   [junit4]   2> 798110 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798111 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c34c3e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_6(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812898, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_7(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812903, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_8(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812919, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_9(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812931, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798111 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 10
   [junit4]   2> 798112 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[24]} 0 0
   [junit4]   2> 798112 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798112 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798115 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d53327f[collection1] main]
   [junit4]   2> 798115 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798116 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d53327f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_6(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812898, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_7(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812903, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_8(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812919, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_9(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812931, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_a(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812936, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798116 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 4
   [junit4]   2> 798117 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[25]} 0 0
   [junit4]   2> 798117 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[26]} 0 0
   [junit4]   2> 798117 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798117 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798120 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cb4229d[collection1] main]
   [junit4]   2> 798120 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798121 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cb4229d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812839, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_1(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812849, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_2(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812859, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_3(9.0.0):c3:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812868, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_4(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812884, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_5(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812891, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_6(9.0.0):c4:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812898, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_7(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812903, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_8(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812919, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_9(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812931, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_a(9.0.0):c1:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812936, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}]) Uninverting(_c(9.0.0):c2:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux, java.vendor=AdoptOpenJDK, timestamp=1581173812941, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0}])))}
   [junit4]   2> 798121 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 3
   [junit4]   2> 798122 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[27]} 0 0
   [junit4]   2> 798122 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 798122 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64940b65 commitCommandVersion:0
   [junit4]   2> 798125 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11a12e0b[collection1] main]
   [junit4]   2> 798125 INFO  (TEST-UninvertDocValuesMergeP

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

n:0
   [junit4]   2> 798739 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@616ba719[collection1] main]
   [junit4]   2> 798740 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 798741 INFO  (searcherExecutor-4216-thread-1) [     ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@616ba719[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_23(9.0.0):C110:[diagnostics={mergeMaxNumSegments=1, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0, timestamp=1581173813539, os=Linux, java.runtime.version=11.0.6+10, mergeFactor=5, os.arch=amd64, source=merge, java.vendor=AdoptOpenJDK, os.version=5.3.0-26-generic}])))}
   [junit4]   2> 798741 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{optimize=} 0 68
   [junit4]   2> 798743 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 798743 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1692894730
   [junit4]   2> 798755 INFO  (coreCloseExecutor-4220-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4671d5b4
   [junit4]   2> 798755 INFO  (coreCloseExecutor-4220-thread-1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@4671d5b4
   [junit4]   2> 798755 INFO  (coreCloseExecutor-4220-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d79d403: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@44a6a35e
   [junit4]   2> 798764 INFO  (coreCloseExecutor-4220-thread-1) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 798796 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 798796 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76ec406b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@739b8489
   [junit4]   2> 798801 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 798802 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33913324: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41097e1f
   [junit4]   2> 798834 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 798834 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@30fcb995: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@66a1fe75
   [junit4]   2> 798844 INFO  (TEST-UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues-seed#[C1E092D1FF33741]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testIndexAndAddDocValues
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=UninvertDocValuesMergePolicyTest -Dtests.method=testIndexAndAddDocValues -Dtests.seed=C1E092D1FF33741 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=os-GE -Dtests.timezone=Europe/Berlin -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 1.18s J1 | UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues <<<
   [junit4]    > Throwable #1: org.junit.ComparisonFailure: expected:<[10]> but was:<[88]>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([C1E092D1FF33741:59F98FEE561477B5]:0)
   [junit4]    > 	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.lambda$testIndexAndAddDocValues$2(UninvertDocValuesMergePolicyTest.java:143)
   [junit4]    > 	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.withNewRawReader(UninvertDocValuesMergePolicyTest.java:233)
   [junit4]    > 	at org.apache.solr.index.UninvertDocValuesMergePolicyTest.testIndexAndAddDocValues(UninvertDocValuesMergePolicyTest.java:118)
   [junit4]    > 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    > 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    > 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    > 	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:834)
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.index.UninvertDocValuesMergePolicyTest_C1E092D1FF33741-001
   [junit4]   2> NOTE: test params are: codec=FastDecompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=1, maxDocsPerChunk=629, blockShift=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=1, blockSize=3)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1ace18f6), locale=os-GE, timezone=Europe/Berlin
   [junit4]   2> NOTE: Linux 5.3.0-26-generic amd64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=16,threads=1,free=163577856,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestExactSharedStatsCache, TestPerFieldSimilarity, BadCopyFieldTest, TestSolrTestCaseJ4, FacetPivotSmallTest, TestGraphTermsQParserPlugin, URLClassifyProcessorTest, TestHighlightDedupGrouping, SolrGraphiteReporterTest, TestNumericTerms64, ForceLeaderTest, JvmMetricsTest, TestSolr4Spatial2, TestSolrCLIRunExample, SolrIndexMetricsTest, HighlighterConfigTest, TestCloudNestedDocsSort, TestTolerantUpdateProcessorRandomCloud, TestSimDistributedQueue, DistributedFacetPivotLargeTest, TestFaceting, TestMiniSolrCloudClusterSSL, TestSolrConfigHandlerConcurrent, NodeLostTriggerIntegrationTest, ResponseLogComponentTest, TestLegacyTerms, BaseCdcrDistributedZkTest, MissingSegmentRecoveryTest, DeleteInactiveReplicaTest, TestSha256AuthenticationProvider, ScheduledTriggerTest, SolrCLIZkUtilsTest, ReplaceNodeNoTargetTest, TestElisionMultitermQuery, CollectionTooManyReplicasTest, CdcrVersionReplicationTest, TemplateUpdateProcessorTest, UtilsToolTest, DirectUpdateHandlerTest, SegmentsInfoRequestHandlerTest, DistributedQueryComponentCustomSortTest, TestRangeQuery, TestDirectoryFactory, HttpPartitionWithTlogReplicasTest, OrderedExecutorTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, HdfsChaosMonkeySafeLeaderTest, LeaderTragicEventTest, TestInitParams, HdfsDirectoryTest, DateFieldTest, TestRandomFlRTGCloud, SolrRrdBackendFactoryTest, BasicAuthOnSingleNodeTest, TestHttpShardHandlerFactory, TestHalfAndHalfDocValues, RandomizedTaggerTest, TestBinaryField, CloudExitableDirectoryReaderTest, ZkCollectionPropsCachingTest, TestMinMaxOnMultiValuedField, ResolveAnalyzerByNameTest, NestedShardedAtomicUpdateTest, CollectionsAPISolrJTest, BasicFunctionalityTest, TestAuthenticationFramework, TestCollapseQParserPlugin, FuzzySearchTest, TestFieldResource, TestRestManager, ExternalFileFieldSortTest, UninvertDocValuesMergePolicyTest]
   [junit4] Completed [478/901 (1!)] on J1 in 1.88s, 2 tests, 1 failure <<< FAILURES!

[...truncated 53936 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25638/consoleText

[repro] Revision: f5c132be6d3fc20f689e630517e7c6be2166f17e

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line:  ant test  -Dtestcase=UninvertDocValuesMergePolicyTest -Dtests.method=testIndexAndAddDocValues -Dtests.seed=C1E092D1FF33741 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=os-GE -Dtests.timezone=Europe/Berlin -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       UninvertDocValuesMergePolicyTest
[repro] ant compile-test

[...truncated 3146 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.UninvertDocValuesMergePolicyTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=C1E092D1FF33741 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=os-GE -Dtests.timezone=Europe/Berlin -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.index.UninvertDocValuesMergePolicyTest
[repro] Exiting with code 0

[...truncated 43 lines...]