You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/08/06 10:17:41 UTC
[JENKINS] Lucene-Solr-7.x-Linux (32bit/jdk1.8.0_172) - Build # 2496
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2496/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testDeleteWithCollection
Error Message:
Error from server at http://127.0.0.1:40219/solr: Could not find collection : testDeleteWithCollection_abc_2
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:40219/solr: Could not find collection : testDeleteWithCollection_abc_2
at __randomizedtesting.SeedInfo.seed([93E3AAFD8AB7DBED:E83A5D10D32D0953]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.cloud.TestWithCollection.testDeleteWithCollection(TestWithCollection.java:224)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest
Error Message:
Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
Stack Trace:
java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
at __randomizedtesting.SeedInfo.seed([93E3AAFD8AB7DBED:83AD4F02F119E21C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:382)
at org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:202)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12655 lines...]
[junit4] Suite: org.apache.solr.update.MaxSizeAutoCommitTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001
[junit4] 2> 229524 INFO (SUITE-MaxSizeAutoCommitTest-seed#[93E3AAFD8AB7DBED]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 229525 INFO (SUITE-MaxSizeAutoCommitTest-seed#[93E3AAFD8AB7DBED]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 229525 INFO (SUITE-MaxSizeAutoCommitTest-seed#[93E3AAFD8AB7DBED]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 229526 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###Starting simpleTest
[junit4] 2> 229526 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 229526 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 229544 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 229562 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 229650 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 231132 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 231133 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 231149 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 231158 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 231158 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 231160 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 231186 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 231197 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 231282 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 231290 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 231291 INFO (coreLoadExecutor-844-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@c1e7da
[junit4] 2> 231291 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 231291 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001/]
[junit4] 2> 231292 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3657354232637403]
[junit4] 2> 231294 WARN (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 231334 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 231334 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 231335 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 231335 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 231336 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=19.1865234375, floorSegmentMB=2.177734375, forceMergeDeletesPctAllowed=26.770236948998054, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6760721855498304, deletesPctAllowed=31.14576895053969
[junit4] 2> 231336 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12bef0f[collection1] main]
[junit4] 2> 231337 WARN (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 231337 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 231337 INFO (coreLoadExecutor-844-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608040154079952896
[junit4] 2> 231338 INFO (searcherExecutor-845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12bef0f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 231340 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 231343 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608040154083098624), 1 (1608040154086244352), 2 (1608040154086244353)]} 0 2
[junit4] 2> 231545 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1608040154297008128), 4 (1608040154298056704), 5 (1608040154298056705)]} 0 1
[junit4] 2> 231747 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1608040154508820480), 7 (1608040154509869056), 8 (1608040154509869057)]} 0 1
[junit4] 2> 231949 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1608040154720632832), 10 (1608040154720632833), 11 (1608040154721681408)]} 0 1
[junit4] 2> 232151 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1608040154932445184), 13 (1608040154932445185), 14 (1608040154933493760)]} 0 1
[junit4] 2> 232353 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1608040155144257536), 16 (1608040155145306112), 17 (1608040155145306113)]} 0 0
[junit4] 2> 232555 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[18 (1608040155356069888), 19 (1608040155356069889), 20 (1608040155357118464)]} 0 1
[junit4] 2> 232757 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[21 (1608040155567882240), 22 (1608040155567882241), 23 (1608040155568930816)]} 0 1
[junit4] 2> 232959 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[24 (1608040155779694592), 25 (1608040155779694593), 26 (1608040155779694594)]} 0 1
[junit4] 2> 232960 INFO (commitScheduler-848-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 232960 INFO (commitScheduler-848-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1770dd4 commitCommandVersion:0
[junit4] 2> 232967 INFO (commitScheduler-848-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c7bbaf[collection1] realtime]
[junit4] 2> 232968 INFO (commitScheduler-848-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 233012 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[27 (1608040155834220544), 28 (1608040155835269120), 29 (1608040155836317696)]} 0 2
[junit4] 2> 233213 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###Ending simpleTest
[junit4] 2> 233213 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 233213 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=25853759
[junit4] 2> 233213 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 233214 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10b1221: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@8463c
[junit4] 2> 233218 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 233218 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e8c1a1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@109e1a1
[junit4] 2> 233221 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 233221 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a58ef: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3bd88d
[junit4] 2> 233222 INFO (coreCloseExecutor-850-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d363fa
[junit4] 2> 233222 INFO (coreCloseExecutor-850-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1d363fa
[junit4] 2> 233222 INFO (coreCloseExecutor-850-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a570c8: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@18d6e19
[junit4] 2> 233229 INFO (coreCloseExecutor-850-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 233229 INFO (coreCloseExecutor-850-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1770dd4 commitCommandVersion:0
[junit4] 2> 233238 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteTest
[junit4] 2> 233239 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 233239 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 233268 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 233281 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 233383 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 234481 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 234481 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 234499 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 234511 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 234511 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 234513 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 234535 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 234551 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 234638 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 234646 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 234647 INFO (coreLoadExecutor-860-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@c1e7da
[junit4] 2> 234647 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 234647 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001/]
[junit4] 2> 234648 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3657354232637403]
[junit4] 2> 234651 WARN (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 234697 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 234698 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 234699 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 234699 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 234700 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=19.1865234375, floorSegmentMB=2.177734375, forceMergeDeletesPctAllowed=26.770236948998054, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6760721855498304, deletesPctAllowed=31.14576895053969
[junit4] 2> 234700 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d76bf8[collection1] main]
[junit4] 2> 234701 WARN (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 234701 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 234701 INFO (coreLoadExecutor-860-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608040157607362560
[junit4] 2> 234702 INFO (searcherExecutor-861-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d76bf8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 234704 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 234715 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 234715 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ee554 commitCommandVersion:0
[junit4] 2> 234733 INFO (commitScheduler-864-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f0dbc9[collection1] realtime]
[junit4] 2> 234734 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 234734 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 234734 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ee554 commitCommandVersion:0
[junit4] 2> 234749 INFO (commitScheduler-864-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c47715[collection1] realtime]
[junit4] 2> 234749 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 234750 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 234750 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ee554 commitCommandVersion:0
[junit4] 2> 234752 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608040157611556864), 1 (1608040157614702592), 2 (1608040157614702593), 3 (1608040157614702594), 4 (1608040157614702595), 5 (1608040157615751168), 6 (1608040157615751169), 7 (1608040157615751170), 8 (1608040157615751171), 9 (1608040157616799744), ... (150 adds)]} 0 47
[junit4] 2> 234759 INFO (commitScheduler-864-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@148174e[collection1] realtime]
[junit4] 2> 234759 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 234955 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[0 (-1608040157871603712), 1 (-1608040157872652288), 2 (-1608040157872652289), 3 (-1608040157872652290), 4 (-1608040157872652291), 5 (-1608040157872652292), 6 (-1608040157872652293), 7 (-1608040157872652294), 8 (-1608040157872652295), 9 (-1608040157873700864), ... (15 deletes)]} 0 2
[junit4] 2> 235157 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[15 (-1608040158084464640), 16 (-1608040158084464641), 17 (-1608040158084464642), 18 (-1608040158084464643), 19 (-1608040158084464644), 20 (-1608040158084464645), 21 (-1608040158084464646), 22 (-1608040158084464647), 23 (-1608040158084464648), 24 (-1608040158084464649), ... (15 deletes)]} 0 1
[junit4] 2> 235359 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[30 (-1608040158296276992), 31 (-1608040158296276993), 32 (-1608040158296276994), 33 (-1608040158296276995), 34 (-1608040158296276996), 35 (-1608040158296276997), 36 (-1608040158296276998), 37 (-1608040158296276999), 38 (-1608040158296277000), 39 (-1608040158296277001), ... (15 deletes)]} 0 1
[junit4] 2> 235560 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[45 (-1608040158507040768), 46 (-1608040158507040769), 47 (-1608040158507040770), 48 (-1608040158508089344), 49 (-1608040158508089345), 50 (-1608040158508089346), 51 (-1608040158508089347), 52 (-1608040158508089348), 53 (-1608040158508089349), 54 (-1608040158508089350), ... (15 deletes)]} 0 1
[junit4] 2> 235560 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 235561 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ee554 commitCommandVersion:0
[junit4] 2> 235563 INFO (commitScheduler-864-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9d3a5e[collection1] realtime]
[junit4] 2> 235564 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 235612 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[60 (-1608040158561566720), 61 (-1608040158561566721), 62 (-1608040158561566722), 63 (-1608040158561566723), 64 (-1608040158561566724), 65 (-1608040158561566725), 66 (-1608040158562615296), 67 (-1608040158562615297), 68 (-1608040158562615298), 69 (-1608040158562615299), ... (15 deletes)]} 0 1
[junit4] 2> 235814 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[75 (-1608040158773379072), 76 (-1608040158773379073), 77 (-1608040158773379074), 78 (-1608040158773379075), 79 (-1608040158773379076), 80 (-1608040158773379077), 81 (-1608040158773379078), 82 (-1608040158774427648), 83 (-1608040158774427649), 84 (-1608040158774427650), ... (15 deletes)]} 0 1
[junit4] 2> 236016 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[90 (-1608040158985191424), 91 (-1608040158985191425), 92 (-1608040158985191426), 93 (-1608040158985191427), 94 (-1608040158985191428), 95 (-1608040158985191429), 96 (-1608040158985191430), 97 (-1608040158985191431), 98 (-1608040158985191432), 99 (-1608040158985191433), ... (15 deletes)]} 0 1
[junit4] 2> 236218 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[105 (-1608040159197003776), 106 (-1608040159197003777), 107 (-1608040159197003778), 108 (-1608040159197003779), 109 (-1608040159197003780), 110 (-1608040159197003781), 111 (-1608040159197003782), 112 (-1608040159197003783), 113 (-1608040159197003784), 114 (-1608040159197003785), ... (15 deletes)]} 0 1
[junit4] 2> 236218 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 236218 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ee554 commitCommandVersion:0
[junit4] 2> 236220 INFO (commitScheduler-864-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b9ef06[collection1] realtime]
[junit4] 2> 236221 INFO (commitScheduler-864-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 236270 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[120 (-1608040159250481152), 121 (-1608040159251529728), 122 (-1608040159251529729), 123 (-1608040159251529730), 124 (-1608040159251529731), 125 (-1608040159251529732), 126 (-1608040159251529733), 127 (-1608040159251529734), 128 (-1608040159251529735), 129 (-1608040159251529736), ... (15 deletes)]} 0 1
[junit4] 2> 236471 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[135 (-1608040159462293504), 136 (-1608040159462293505), 137 (-1608040159463342080), 138 (-1608040159463342081), 139 (-1608040159463342082), 140 (-1608040159463342083), 141 (-1608040159463342084), 142 (-1608040159463342085), 143 (-1608040159463342086), 144 (-1608040159463342087), ... (15 deletes)]} 0 1
[junit4] 2> 236672 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteTest
[junit4] 2> 236672 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 236673 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=16485562
[junit4] 2> 236673 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 236673 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14c1190: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@10e2a03
[junit4] 2> 236683 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 236683 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16e16ec: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@109e1a1
[junit4] 2> 236688 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 236688 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d5f89: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3bd88d
[junit4] 2> 236689 INFO (coreCloseExecutor-866-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@881394
[junit4] 2> 236689 INFO (coreCloseExecutor-866-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=881394
[junit4] 2> 236689 INFO (coreCloseExecutor-866-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d07cd3: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@15f6ca7
[junit4] 2> 236698 INFO (coreCloseExecutor-866-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 236698 INFO (coreCloseExecutor-866-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ee554 commitCommandVersion:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=93E3AAFD8AB7DBED -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=Etc/GMT-9 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 3.47s J1 | MaxSizeAutoCommitTest.deleteTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
[junit4] > at __randomizedtesting.SeedInfo.seed([93E3AAFD8AB7DBED:83AD4F02F119E21C]:0)
[junit4] > at org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:382)
[junit4] > at org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:202)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 236705 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ###Starting endToEndTest {seed=[93E3AAFD8AB7DBED:89B5C5CB6A05FCEF]}
[junit4] 2> 236705 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 236706 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 236737 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 236749 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 236837 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 238289 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 238289 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 238303 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 238311 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 238311 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1e7da
[junit4] 2> 238313 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 238347 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 238376 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 238472 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 238488 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 238488 INFO (coreLoadExecutor-876-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@c1e7da
[junit4] 2> 238488 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 238488 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_93E3AAFD8AB7DBED-001/init-core-data-001/]
[junit4] 2> 238491 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3657354232637403]
[junit4] 2> 238492 WARN (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 238532 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 238533 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 238533 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 238533 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 238534 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=19.1865234375, floorSegmentMB=2.177734375, forceMergeDeletesPctAllowed=26.770236948998054, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6760721855498304, deletesPctAllowed=31.14576895053969
[junit4] 2> 238535 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@120474c[collection1] main]
[junit4] 2> 238535 WARN (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 238535 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 238536 INFO (coreLoadExecutor-876-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608040161628651520
[junit4] 2> 238537 INFO (searcherExecutor-877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@120474c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 238538 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 238551 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608040161631797248), 1 (1608040161641234432), 2 (1608040161642283008), 3 (1608040161642283009), 4 (1608040161642283010), 5 (1608040161642283011), 6 (1608040161642283012), 7 (1608040161642283013), 8 (1608040161642283014), 9 (1608040161643331584), ... (20 adds)]} 0 12
[junit4] 2> 238753 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=214
[junit4] 2> 238757 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1608040161857241088), 21 (1608040161857241089), 22 (1608040161858289664), 23 (1608040161858289665), 24 (1608040161858289666), 25 (1608040161858289667), 26 (1608040161858289668), 27 (1608040161858289669), 28 (1608040161859338240), 29 (1608040161859338241), ... (20 adds)]} 0 3
[junit4] 2> 238958 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=419
[junit4] 2> 238962 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1608040162072199168), 41 (1608040162072199169), 42 (1608040162072199170), 43 (1608040162072199171), 44 (1608040162073247744), 45 (1608040162073247745), 46 (1608040162073247746), 47 (1608040162073247747), 48 (1608040162073247748), 49 (1608040162073247749), ... (20 adds)]} 0 3
[junit4] 2> 239163 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=624
[junit4] 2> 239167 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1608040162286108672), 61 (1608040162287157248), 62 (1608040162287157249), 63 (1608040162287157250), 64 (1608040162287157251), 65 (1608040162287157252), 66 (1608040162287157253), 67 (1608040162288205824), 68 (1608040162288205825), 69 (1608040162288205826), ... (20 adds)]} 0 3
[junit4] 2> 239367 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=829
[junit4] 2> 239371 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1608040162501066752), 81 (1608040162501066753), 82 (1608040162501066754), 83 (1608040162502115328), 84 (1608040162502115329), 85 (1608040162502115330), 86 (1608040162502115331), 87 (1608040162502115332), 88 (1608040162502115333), 89 (1608040162502115334), ... (20 adds)]} 0 3
[junit4] 2> 239572 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1033
[junit4] 2> 239576 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1608040162716024832), 101 (1608040162716024833), 102 (1608040162716024834), 103 (1608040162716024835), 104 (1608040162716024836), 105 (1608040162717073408), 106 (1608040162717073409), 107 (1608040162717073410), 108 (1608040162717073411), 109 (1608040162717073412), ... (20 adds)]} 0 3
[junit4] 2> 239777 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1238
[junit4] 2> 239781 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1608040162929934336), 121 (1608040162929934337), 122 (1608040162929934338), 123 (1608040162930982912), 124 (1608040162930982913), 125 (1608040162930982914), 126 (1608040162930982915), 127 (1608040162930982916), 128 (1608040162932031488), 129 (1608040162932031489), ... (20 adds)]} 0 3
[junit4] 2> 239781 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 239782 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 239790 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@854fad[collection1] realtime]
[junit4] 2> 239791 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 239831 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1292
[junit4] 2> 239836 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1608040162987606016), 141 (1608040162988654592), 142 (1608040162988654593), 143 (1608040162988654594), 144 (1608040162989703168), 145 (1608040162989703169), 146 (1608040162989703170), 147 (1608040162989703171), 148 (1608040162989703172), 149 (1608040162989703173), ... (20 adds)]} 0 4
[junit4] 2> 240037 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1498
[junit4] 2> 240040 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1608040163202564096), 161 (1608040163203612672), 162 (1608040163203612673), 163 (1608040163203612674), 164 (1608040163203612675), 165 (1608040163203612676), 166 (1608040163203612677), 167 (1608040163203612678), 168 (1608040163204661248), 169 (1608040163204661249), ... (20 adds)]} 0 3
[junit4] 2> 240241 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1702
[junit4] 2> 240244 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1608040163417522176), 181 (1608040163417522177), 182 (1608040163417522178), 183 (1608040163417522179), 184 (1608040163417522180), 185 (1608040163417522181), 186 (1608040163418570752), 187 (1608040163418570753), 188 (1608040163418570754), 189 (1608040163418570755), ... (20 adds)]} 0 2
[junit4] 2> 240445 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1906
[junit4] 2> 240448 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 (1608040163631431680), 201 (1608040163631431681), 202 (1608040163631431682), 203 (1608040163631431683), 204 (1608040163631431684), 205 (1608040163632480256), 206 (1608040163632480257), 207 (1608040163632480258), 208 (1608040163632480259), 209 (1608040163632480260), ... (20 adds)]} 0 2
[junit4] 2> 240649 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2111
[junit4] 2> 240653 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 (1608040163845341184), 221 (1608040163845341185), 222 (1608040163845341186), 223 (1608040163845341187), 224 (1608040163846389760), 225 (1608040163846389761), 226 (1608040163846389762), 227 (1608040163846389763), 228 (1608040163846389764), 229 (1608040163846389765), ... (20 adds)]} 0 2
[junit4] 2> 240854 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2315
[junit4] 2> 240857 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 (1608040164059250688), 241 (1608040164059250689), 242 (1608040164059250690), 243 (1608040164059250691), 244 (1608040164059250692), 245 (1608040164060299264), 246 (1608040164060299265), 247 (1608040164060299266), 248 (1608040164060299267), 249 (1608040164060299268), ... (20 adds)]} 0 2
[junit4] 2> 241058 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2519
[junit4] 2> 241062 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 (1608040164273160192), 261 (1608040164273160193), 262 (1608040164273160194), 263 (1608040164274208768), 264 (1608040164274208769), 265 (1608040164274208770), 266 (1608040164274208771), 267 (1608040164274208772), 268 (1608040164275257344), 269 (1608040164275257345), ... (20 adds)]} 0 4
[junit4] 2> 241062 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 241062 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 241073 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9036e5[collection1] realtime]
[junit4] 2> 241074 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 241113 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2574
[junit4] 2> 241117 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 (1608040164330831872), 281 (1608040164331880448), 282 (1608040164331880449), 283 (1608040164331880450), 284 (1608040164332929024), 285 (1608040164332929025), 286 (1608040164332929026), 287 (1608040164332929027), 288 (1608040164332929028), 289 (1608040164332929029), ... (20 adds)]} 0 3
[junit4] 2> 241318 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2779
[junit4] 2> 241321 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 (1608040164545789952), 301 (1608040164545789953), 302 (1608040164545789954), 303 (1608040164545789955), 304 (1608040164546838528), 305 (1608040164546838529), 306 (1608040164546838530), 307 (1608040164546838531), 308 (1608040164546838532), 309 (1608040164546838533), ... (20 adds)]} 0 2
[junit4] 2> 241521 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2983
[junit4] 2> 241524 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 (1608040164759699456), 321 (1608040164759699457), 322 (1608040164759699458), 323 (1608040164759699459), 324 (1608040164759699460), 325 (1608040164760748032), 326 (1608040164760748033), 327 (1608040164760748034), 328 (1608040164760748035), 329 (1608040164760748036), ... (20 adds)]} 0 2
[junit4] 2> 241725 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3186
[junit4] 2> 241728 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 (1608040164973608960), 341 (1608040164973608961), 342 (1608040164973608962), 343 (1608040164973608963), 344 (1608040164973608964), 345 (1608040164973608965), 346 (1608040164974657536), 347 (1608040164974657537), 348 (1608040164974657538), 349 (1608040164974657539), ... (20 adds)]} 0 2
[junit4] 2> 241929 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3390
[junit4] 2> 241932 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 (1608040165187518464), 361 (1608040165187518465), 362 (1608040165187518466), 363 (1608040165187518467), 364 (1608040165187518468), 365 (1608040165187518469), 366 (1608040165187518470), 367 (1608040165188567040), 368 (1608040165188567041), 369 (1608040165188567042), ... (20 adds)]} 0 2
[junit4] 2> 242133 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3594
[junit4] 2> 242136 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 (1608040165400379392), 381 (1608040165401427968), 382 (1608040165401427969), 383 (1608040165401427970), 384 (1608040165401427971), 385 (1608040165401427972), 386 (1608040165401427973), 387 (1608040165402476544), 388 (1608040165402476545), 389 (1608040165402476546), ... (20 adds)]} 0 3
[junit4] 2> 242337 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3798
[junit4] 2> 242342 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 (1608040165615337472), 401 (1608040165615337473), 402 (1608040165615337474), 403 (1608040165616386048), 404 (1608040165616386049), 405 (1608040165616386050), 406 (1608040165616386051), 407 (1608040165616386052), 408 (1608040165617434624), 409 (1608040165617434625), ... (20 adds)]} 0 3
[junit4] 2> 242342 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 242342 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 242352 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1373577[collection1] realtime]
[junit4] 2> 242353 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 242392 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3853
[junit4] 2> 242396 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 (1608040165673009152), 421 (1608040165674057728), 422 (1608040165674057729), 423 (1608040165674057730), 424 (1608040165674057731), 425 (1608040165674057732), 426 (1608040165674057733), 427 (1608040165675106304), 428 (1608040165675106305), 429 (1608040165675106306), ... (20 adds)]} 0 3
[junit4] 2> 242597 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4058
[junit4] 2> 242600 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 (1608040165886918656), 441 (1608040165887967232), 442 (1608040165887967233), 443 (1608040165887967234), 444 (1608040165887967235), 445 (1608040165887967236), 446 (1608040165887967237), 447 (1608040165887967238), 448 (1608040165887967239), 449 (1608040165889015808), ... (20 adds)]} 0 2
[junit4] 2> 242801 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4262
[junit4] 2> 242803 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 (1608040166100828160), 461 (1608040166100828161), 462 (1608040166100828162), 463 (1608040166101876736), 464 (1608040166101876737), 465 (1608040166101876738), 466 (1608040166101876739), 467 (1608040166101876740), 468 (1608040166101876741), 469 (1608040166101876742), ... (20 adds)]} 0 2
[junit4] 2> 243004 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4465
[junit4] 2> 243007 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 (1608040166314737664), 481 (1608040166314737665), 482 (1608040166314737666), 483 (1608040166314737667), 484 (1608040166314737668), 485 (1608040166314737669), 486 (1608040166314737670), 487 (1608040166315786240), 488 (1608040166315786241), 489 (1608040166315786242), ... (20 adds)]} 0 2
[junit4] 2> 243208 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4669
[junit4] 2> 243210 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1608040166527598592), 501 (1608040166527598593), 502 (1608040166527598594), 503 (1608040166528647168), 504 (1608040166528647169), 505 (1608040166528647170), 506 (1608040166528647171), 507 (1608040166528647172), 508 (1608040166528647173), 509 (1608040166528647174), ... (20 adds)]} 0 2
[junit4] 2> 243411 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4872
[junit4] 2> 243414 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 (1608040166741508096), 521 (1608040166741508097), 522 (1608040166741508098), 523 (1608040166741508099), 524 (1608040166741508100), 525 (1608040166741508101), 526 (1608040166741508102), 527 (1608040166742556672), 528 (1608040166742556673), 529 (1608040166742556674), ... (20 adds)]} 0 2
[junit4] 2> 243615 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5076
[junit4] 2> 243618 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 (1608040166954369024), 541 (1608040166955417600), 542 (1608040166955417601), 543 (1608040166955417602), 544 (1608040166955417603), 545 (1608040166955417604), 546 (1608040166955417605), 547 (1608040166955417606), 548 (1608040166955417607), 549 (1608040166956466176), ... (20 adds)]} 0 2
[junit4] 2> 243618 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 243618 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 243625 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1febdb4[collection1] realtime]
[junit4] 2> 243626 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 243668 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5129
[junit4] 2> 243671 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 (1608040167010992128), 561 (1608040167012040704), 562 (1608040167012040705), 563 (1608040167012040706), 564 (1608040167012040707), 565 (1608040167012040708), 566 (1608040167012040709), 567 (1608040167012040710), 568 (1608040167012040711), 569 (1608040167012040712), ... (20 adds)]} 0 2
[junit4] 2> 243872 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5333
[junit4] 2> 243875 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 (1608040167224901632), 581 (1608040167224901633), 582 (1608040167224901634), 583 (1608040167224901635), 584 (1608040167224901636), 585 (1608040167224901637), 586 (1608040167224901638), 587 (1608040167224901639), 588 (1608040167225950208), 589 (1608040167225950209), ... (20 adds)]} 0 2
[junit4] 2> 244076 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5537
[junit4] 2> 244078 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 (1608040167437762560), 601 (1608040167437762561), 602 (1608040167437762562), 603 (1608040167437762563), 604 (1608040167437762564), 605 (1608040167437762565), 606 (1608040167438811136), 607 (1608040167438811137), 608 (1608040167438811138), 609 (1608040167438811139), ... (20 adds)]} 0 1
[junit4] 2> 244279 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5740
[junit4] 2> 244281 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 (1608040167650623488), 621 (1608040167650623489), 622 (1608040167650623490), 623 (1608040167650623491), 624 (1608040167651672064), 625 (1608040167651672065), 626 (1608040167651672066), 627 (1608040167651672067), 628 (1608040167651672068), 629 (1608040167651672069), ... (20 adds)]} 0 2
[junit4] 2> 244482 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5943
[junit4] 2> 244485 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 (1608040167863484416), 641 (1608040167864532992), 642 (1608040167864532993), 643 (1608040167864532994), 644 (1608040167864532995), 645 (1608040167864532996), 646 (1608040167864532997), 647 (1608040167864532998), 648 (1608040167864532999), 649 (1608040167865581568), ... (20 adds)]} 0 2
[junit4] 2> 244686 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6147
[junit4] 2> 244688 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 (1608040168077393920), 661 (1608040168077393921), 662 (1608040168077393922), 663 (1608040168077393923), 664 (1608040168077393924), 665 (1608040168078442496), 666 (1608040168078442497), 667 (1608040168078442498), 668 (1608040168078442499), 669 (1608040168078442500), ... (20 adds)]} 0 2
[junit4] 2> 244889 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6350
[junit4] 2> 244891 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 (1608040168290254848), 681 (1608040168290254849), 682 (1608040168291303424), 683 (1608040168291303425), 684 (1608040168291303426), 685 (1608040168291303427), 686 (1608040168291303428), 687 (1608040168291303429), 688 (1608040168291303430), 689 (1608040168291303431), ... (20 adds)]} 0 2
[junit4] 2> 244892 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 244892 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 244898 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b35c54[collection1] realtime]
[junit4] 2> 244898 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 244942 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6403
[junit4] 2> 244945 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[700 (1608040168345829376), 701 (1608040168346877952), 702 (1608040168346877953), 703 (1608040168346877954), 704 (1608040168346877955), 705 (1608040168347926528), 706 (1608040168347926529), 707 (1608040168347926530), 708 (1608040168347926531), 709 (1608040168347926532), ... (20 adds)]} 0 2
[junit4] 2> 245146 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6607
[junit4] 2> 245148 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[720 (1608040168559738880), 721 (1608040168559738881), 722 (1608040168559738882), 723 (1608040168560787456), 724 (1608040168560787457), 725 (1608040168560787458), 726 (1608040168560787459), 727 (1608040168560787460), 728 (1608040168560787461), 729 (1608040168560787462), ... (20 adds)]} 0 2
[junit4] 2> 245349 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6810
[junit4] 2> 245351 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[740 (1608040168772599808), 741 (1608040168773648384), 742 (1608040168773648385), 743 (1608040168773648386), 744 (1608040168773648387), 745 (1608040168773648388), 746 (1608040168773648389), 747 (1608040168773648390), 748 (1608040168773648391), 749 (1608040168773648392), ... (20 adds)]} 0 2
[junit4] 2> 245552 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7013
[junit4] 2> 245555 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[760 (1608040168986509312), 761 (1608040168986509313), 762 (1608040168986509314), 763 (1608040168986509315), 764 (1608040168987557888), 765 (1608040168987557889), 766 (1608040168987557890), 767 (1608040168987557891), 768 (1608040168987557892), 769 (1608040168987557893), ... (20 adds)]} 0 2
[junit4] 2> 245756 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7217
[junit4] 2> 245758 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[780 (1608040169200418816), 781 (1608040169200418817), 782 (1608040169200418818), 783 (1608040169200418819), 784 (1608040169200418820), 785 (1608040169200418821), 786 (1608040169200418822), 787 (1608040169200418823), 788 (1608040169200418824), 789 (1608040169200418825), ... (20 adds)]} 0 2
[junit4] 2> 245959 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7421
[junit4] 2> 245962 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[800 (1608040169413279744), 801 (1608040169413279745), 802 (1608040169413279746), 803 (1608040169413279747), 804 (1608040169413279748), 805 (1608040169413279749), 806 (1608040169413279750), 807 (1608040169413279751), 808 (1608040169414328320), 809 (1608040169414328321), ... (20 adds)]} 0 2
[junit4] 2> 246163 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7624
[junit4] 2> 246165 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[820 (1608040169626140672), 821 (1608040169626140673), 822 (1608040169626140674), 823 (1608040169626140675), 824 (1608040169626140676), 825 (1608040169627189248), 826 (1608040169627189249), 827 (1608040169627189250), 828 (1608040169627189251), 829 (1608040169627189252), ... (20 adds)]} 0 2
[junit4] 2> 246166 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 246166 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 246171 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dbbc16[collection1] realtime]
[junit4] 2> 246172 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 246216 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7677
[junit4] 2> 246219 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[840 (1608040169681715200), 841 (1608040169682763776), 842 (1608040169682763777), 843 (1608040169682763778), 844 (1608040169682763779), 845 (1608040169682763780), 846 (1608040169682763781), 847 (1608040169682763782), 848 (1608040169682763783), 849 (1608040169683812352), ... (20 adds)]} 0 2
[junit4] 2> 246419 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7880
[junit4] 2> 246422 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[860 (1608040169895624704), 861 (1608040169895624705), 862 (1608040169895624706), 863 (1608040169895624707), 864 (1608040169895624708), 865 (1608040169895624709), 866 (1608040169895624710), 867 (1608040169896673280), 868 (1608040169896673281), 869 (1608040169896673282), ... (20 adds)]} 0 2
[junit4] 2> 246623 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8084
[junit4] 2> 246625 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[880 (1608040170108485632), 881 (1608040170108485633), 882 (1608040170108485634), 883 (1608040170108485635), 884 (1608040170108485636), 885 (1608040170108485637), 886 (1608040170109534208), 887 (1608040170109534209), 888 (1608040170109534210), 889 (1608040170109534211), ... (20 adds)]} 0 1
[junit4] 2> 246826 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8287
[junit4] 2> 246828 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[900 (1608040170321346560), 901 (1608040170321346561), 902 (1608040170321346562), 903 (1608040170321346563), 904 (1608040170321346564), 905 (1608040170322395136), 906 (1608040170322395137), 907 (1608040170322395138), 908 (1608040170322395139), 909 (1608040170322395140), ... (20 adds)]} 0 1
[junit4] 2> 247029 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8490
[junit4] 2> 247031 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[920 (1608040170534207488), 921 (1608040170534207489), 922 (1608040170534207490), 923 (1608040170535256064), 924 (1608040170535256065), 925 (1608040170535256066), 926 (1608040170535256067), 927 (1608040170535256068), 928 (1608040170535256069), 929 (1608040170535256070), ... (20 adds)]} 0 2
[junit4] 2> 247232 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8693
[junit4] 2> 247234 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 (1608040170747068416), 941 (1608040170748116992), 942 (1608040170748116993), 943 (1608040170748116994), 944 (1608040170748116995), 945 (1608040170748116996), 946 (1608040170748116997), 947 (1608040170748116998), 948 (1608040170748116999), 949 (1608040170748117000), ... (20 adds)]} 0 1
[junit4] 2> 247435 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8896
[junit4] 2> 247437 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 (1608040170959929344), 961 (1608040170960977920), 962 (1608040170960977921), 963 (1608040170960977922), 964 (1608040170960977923), 965 (1608040170960977924), 966 (1608040170960977925), 967 (1608040170960977926), 968 (1608040170960977927), 969 (1608040170960977928), ... (20 adds)]} 0 1
[junit4] 2> 247438 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 247438 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffee5 commitCommandVersion:0
[junit4] 2> 247443 INFO (commitScheduler-880-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8e61b0[collection1] realtime]
[junit4] 2> 247444 INFO (commitScheduler-880-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 247488 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8949
[junit4] 2> 247491 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 (1608040171015503872), 981 (1608040171016552448), 982 (1608040171016552449), 983 (1608040171016552450), 984 (1608040171016552451), 985 (1608040171016552452), 986 (1608040171017601024), 987 (1608040171017601025), 988 (1608040171017601026), 989 (1608040171017601027), ... (20 adds)]} 0 2
[junit4] 2> 247692 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=9153
[junit4] 2> 247692 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[93E3AAFD8AB7DBED]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=
[...truncated too long message...]
ithCollections_abc/terms/shard1
[junit4] 2> 12 /solr/collections/testAddReplica_abc/terms/shard1
[junit4] 2> 12 /solr/collections/testMoveReplicaMainCollection_xyz/terms/shard1
[junit4] 2> 12 /solr/collections/testMultipleWithCollections_xyz/terms/shard1
[junit4] 2> 10 /solr/collections/testMultipleWithCollections_xyz2/terms/shard1
[junit4] 2> 10 /solr/collections/testAddReplica_xyz/terms/shard1
[junit4] 2> 10 /solr/collections/testAddReplicaWithPolicy_xyz/terms/shard1
[junit4] 2> 5 /solr/security.json
[junit4] 2> 3 /solr/configs/testNodeAdded_xyz.AUTOCREATED
[junit4] 2> 3 /solr/collections/testCreateCollection_abc/terms/shard1
[junit4] 2> 3 /solr/configs/testNodeAdded_abc.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/collections/testCreateCollectionNoWithCollection_abc/terms/shard2
[junit4] 2> 3 /solr/collections/testCreateCollectionNoWithCollection_abc/terms/shard1
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_abc_2/terms/shard1
[junit4] 2> 3 /solr/configs/testNodeAdded_xyz.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/collections/testCreateCollection_xyz/terms/shard1
[junit4] 2> 3 /solr/configs/testNodeAdded_abc.AUTOCREATED
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_xyz/terms/shard1
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_abc/terms/shard1
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_xyz_2/terms/shard1
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplica_abc.AUTOCREATED
[junit4] 2> 2 /solr/collections/testMoveReplicaWithCollection_abc/terms/shard1
[junit4] 2> 2 /solr/configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz2.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplica_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc2.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc2.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplica_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplica_xyz.AUTOCREATED
[junit4] 2> 2 /solr/collections/testMoveReplicaWithCollection_xyz/terms/shard1
[junit4] 2> 2 /solr/configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz2.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 45 /solr/aliases.json
[junit4] 2> 20 /solr/clusterstate.json
[junit4] 2> 6 /solr/collections/testNodeAdded_xyz/state.json
[junit4] 2> 6 /solr/collections/testMoveReplicaMainCollection_xyz/state.json
[junit4] 2> 6 /solr/collections/testMultipleWithCollections_abc2/state.json
[junit4] 2> 6 /solr/collections/testNodeAdded_abc/state.json
[junit4] 2> 5 /solr/collections/testMultipleWithCollections_xyz2/state.json
[junit4] 2> 5 /solr/collections/testMoveReplicaMainCollection_abc/state.json
[junit4] 2> 5 /solr/collections/testMultipleWithCollections_abc/state.json
[junit4] 2> 5 /solr/collections/testAddReplica_abc/state.json
[junit4] 2> 5 /solr/collections/testMultipleWithCollections_xyz/state.json
[junit4] 2> 4 /solr/collections/testAddReplica_xyz/state.json
[junit4] 2> 4 /solr/collections/testAddReplicaWithPolicy_xyz/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72113280130678791-127.0.0.1:40219_solr-n_0000000001
[junit4] 2> 2 /solr/collections/testCreateCollectionNoWithCollection_abc/state.json
[junit4] 2> 2 /solr/collections/testAddReplicaWithPolicy_abc/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 192 /solr/collections
[junit4] 2> 42 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_93E3AAFD8AB7DBED-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1116, maxMBSortInHeap=7.646197335863857, sim=RandomSimilarity(queryNorm=false): {}, locale=ar-SD, timezone=Etc/GMT+7
[junit4] 2> NOTE: Linux 4.15.0-29-generic i386/Oracle Corporation 1.8.0_172 (32-bit)/cpus=8,threads=1,free=149224512,total=377532416
[junit4] 2> NOTE: All tests run in this JVM: [CdcrReplicationHandlerTest, TestMinMaxOnMultiValuedField, HdfsDirectoryTest, TriLevelCompositeIdRoutingTest, TestNumericRangeQuery32, TestStressRecovery, BasicZkTest, TestLegacyNumericUtils, CustomHighlightComponentTest, SolrTestCaseJ4Test, V2StandaloneTest, TestSSLRandomization, AlternateDirectoryTest, TestDFISimilarityFactory, RandomizedTaggerTest, SimplePostToolTest, UpdateRequestProcessorFactoryTest, AutoScalingHandlerTest, TestBlendedInfixSuggestions, HDFSCollectionsAPITest, SolrCmdDistributorTest, TestMaxTokenLenTokenizer, URLClassifyProcessorTest, SystemInfoHandlerTest, TestHdfsCloudBackupRestore, TestConfig, ScheduledTriggerIntegrationTest, ShardRoutingCustomTest, ForceLeaderTest, TestSha256AuthenticationProvider, TestMaxScoreQueryParser, TestHashQParserPlugin, TestAuthorizationFramework, SOLR749Test, TriggerCooldownIntegrationTest, TestExecutePlanAction, MetricsHistoryHandlerTest, TestSchemaResource, DateMathParserTest, TestSchemalessBufferedUpdates, TestDirectoryFactory, ClusterStateUpdateTest, RecoveryZkTest, QueryParsingTest, LukeRequestHandlerTest, HdfsAutoAddReplicasIntegrationTest, ExplicitHLLTest, JsonLoaderTest, DistributedExpandComponentTest, TestConfigSetProperties, TestClusterStateMutator, DistributedFacetPivotWhiteBoxTest, TestJsonFacetRefinement, TestSort, TestJmxIntegration, DistanceFunctionTest, RequestLoggingTest, TestSolrFieldCacheBean, TestSizeLimitedDistributedMap, FieldMutatingUpdateProcessorTest, TestComplexPhraseQParserPlugin, TestTrie, TestConfigsApi, BlockJoinFacetRandomTest, SuggestComponentTest, TestMiniSolrCloudClusterSSL, ClassificationUpdateProcessorIntegrationTest, TestRangeQuery, ImplicitSnitchTest, TestFieldTypeCollectionResource, SchemaApiFailureTest, TestUpdate, DistributedQueueTest, TestBackupRepositoryFactory, TestSimDistributedQueue, TestCloudJSONFacetJoinDomain, TestConfigSetsAPIZkFailure, TestExactSharedStatsCache, HdfsRecoverLeaseTest, TestManagedSynonymFilterFactory, TestPerFieldSimilarity, TestManagedResource, TestWriterPerf, TestNodeAddedTrigger, PeerSyncTest, NumberUtilsTest, CustomCollectionTest, UpdateParamsTest, TestSerializedLuceneMatchVersion, CoreAdminHandlerTest, DistributedFacetPivotLongTailTest, TestRealTimeGet, TestCloudPseudoReturnFields, DistributedIntervalFacetingTest, DateRangeFieldTest, TestLeaderElectionZkExpiry, TestCSVResponseWriter, TestNoOpRegenerator, TestSolrConfigHandlerConcurrent, TestRandomRequestDistribution, SolrJmxReporterCloudTest, LargeFieldTest, CoreMergeIndexesAdminHandlerTest, ConnectionManagerTest, TestReplicationHandlerBackup, TestLMDirichletSimilarityFactory, TestScoreJoinQPScore, OverseerTest, LeaderFailoverAfterPartitionTest, TestPostingsSolrHighlighter, HdfsUnloadDistributedZkTest, TestWithCollection]
[junit4] Completed [315/827 (2!)] on J2 in 113.31s, 9 tests, 1 error <<< FAILURES!
[...truncated 46430 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2496/consoleText
[repro] Revision: 3712b8fc2b91648a8f2400700c47337a0322f6b6
[repro] Ant options: "-Dargs=-client -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=93E3AAFD8AB7DBED -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=Etc/GMT-9 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TestWithCollection -Dtests.method=testDeleteWithCollection -Dtests.seed=93E3AAFD8AB7DBED -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SD -Dtests.timezone=Etc/GMT+7 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] MaxSizeAutoCommitTest
[repro] TestWithCollection
[repro] ant compile-test
[...truncated 3334 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.MaxSizeAutoCommitTest|*.TestWithCollection" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseSerialGC" -Dtests.seed=93E3AAFD8AB7DBED -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=Etc/GMT-9 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 167 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.TestWithCollection
[repro] 0/5 failed: org.apache.solr.update.MaxSizeAutoCommitTest
[repro] Exiting with code 0
[...truncated 40 lines...]