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/06/14 18:03:25 UTC
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_172) - Build #
22250 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22250/
Java: 32bit/jdk1.8.0_172 -server -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest
Error Message:
Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001/tlog/tlog.0000000000000000005, tlog size: 1276
Stack Trace:
java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001/tlog/tlog.0000000000000000005, tlog size: 1276
at __randomizedtesting.SeedInfo.seed([55A691B33D8C39F0:45E8744C46220001]: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:379)
at org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:200)
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 12667 lines...]
[junit4] Suite: org.apache.solr.update.MaxSizeAutoCommitTest
[junit4] 2> 268838 INFO (SUITE-MaxSizeAutoCommitTest-seed#[55A691B33D8C39F0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001
[junit4] 2> 268839 WARN (SUITE-MaxSizeAutoCommitTest-seed#[55A691B33D8C39F0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 268839 INFO (SUITE-MaxSizeAutoCommitTest-seed#[55A691B33D8C39F0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 268840 INFO (SUITE-MaxSizeAutoCommitTest-seed#[55A691B33D8C39F0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 268842 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting simpleTest
[junit4] 2> 269087 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 269087 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 269101 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 269121 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 269189 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 269552 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 269553 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 269567 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 269574 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 269574 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 269576 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 269595 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 269606 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 269662 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 269667 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 269667 INFO (coreLoadExecutor-1078-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@b3360d
[junit4] 2> 269667 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 269667 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001/]
[junit4] 2> 269668 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=6.2529296875, floorSegmentMB=0.97265625, forceMergeDeletesPctAllowed=0.4385253564230662, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 269678 WARN (coreLoadExecutor-1078-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> 269702 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 269702 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 269703 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 269703 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 269704 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=68.46484375, floorSegmentMB=1.2607421875, forceMergeDeletesPctAllowed=28.30374423521128, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6072955310095851
[junit4] 2> 269704 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f6d81d[collection1] main]
[junit4] 2> 269705 WARN (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 269705 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 269705 INFO (coreLoadExecutor-1078-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1603268267810488320
[junit4] 2> 269706 INFO (searcherExecutor-1079-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f6d81d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 269707 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 269713 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1603268267813634048), 1 (1603268267815731200), 2 (1603268267815731201)]} 0 5
[junit4] 2> 269919 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1603268268029640704), 4 (1603268268030689280), 5 (1603268268030689281)]} 0 4
[junit4] 2> 270123 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1603268268245647360), 7 (1603268268245647361), 8 (1603268268245647362)]} 0 3
[junit4] 2> 270328 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1603268268459556864), 10 (1603268268459556865), 11 (1603268268460605440)]} 0 4
[junit4] 2> 270532 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1603268268674514944), 13 (1603268268674514945), 14 (1603268268674514946)]} 0 3
[junit4] 2> 270737 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1603268268888424448), 16 (1603268268889473024), 17 (1603268268889473025)]} 0 4
[junit4] 2> 270943 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[18 (1603268269103382528), 19 (1603268269104431104), 20 (1603268269104431105)]} 0 5
[junit4] 2> 271148 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[21 (1603268269319389184), 22 (1603268269319389185), 23 (1603268269320437760)]} 0 4
[junit4] 2> 271354 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[24 (1603268269534347264), 25 (1603268269534347265), 26 (1603268269534347266)]} 0 5
[junit4] 2> 271355 INFO (commitScheduler-1082-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 271355 INFO (commitScheduler-1082-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dcc1e0 commitCommandVersion:0
[junit4] 2> 271358 INFO (commitScheduler-1082-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1da89b2[collection1] realtime]
[junit4] 2> 271359 INFO (commitScheduler-1082-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 271409 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[27 (1603268269592018944), 28 (1603268269594116096), 29 (1603268269594116097)]} 0 4
[junit4] 2> 271610 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending simpleTest
[junit4] 2> 271610 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 271610 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=706084
[junit4] 2> 271610 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 271610 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@345b4a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1f45ee1
[junit4] 2> 271616 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 271616 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c0895a: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5592a3
[junit4] 2> 271619 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 271619 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52a80d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@12a0e2c
[junit4] 2> 271620 INFO (coreCloseExecutor-1084-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f76ab8
[junit4] 2> 271620 INFO (coreCloseExecutor-1084-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=f76ab8
[junit4] 2> 271620 INFO (coreCloseExecutor-1084-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33025e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@b61184
[junit4] 2> 271628 INFO (coreCloseExecutor-1084-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 271628 INFO (coreCloseExecutor-1084-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dcc1e0 commitCommandVersion:0
[junit4] 2> 271635 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteTest
[junit4] 2> 271636 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 271636 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 271653 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 271663 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 271735 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 272029 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 272029 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 272057 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 272065 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 272066 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 272068 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 272086 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 272094 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 272148 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 272155 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 272156 INFO (coreLoadExecutor-1094-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@b3360d
[junit4] 2> 272156 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 272156 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001/]
[junit4] 2> 272158 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=6.2529296875, floorSegmentMB=0.97265625, forceMergeDeletesPctAllowed=0.4385253564230662, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 272160 WARN (coreLoadExecutor-1094-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> 272195 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 272195 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 272196 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 272196 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 272197 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=68.46484375, floorSegmentMB=1.2607421875, forceMergeDeletesPctAllowed=28.30374423521128, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6072955310095851
[junit4] 2> 272197 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1347a2f[collection1] main]
[junit4] 2> 272197 WARN (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 272198 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 272199 INFO (searcherExecutor-1095-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1347a2f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 272199 INFO (coreLoadExecutor-1094-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1603268270425636864
[junit4] 2> 272201 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 272253 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 272253 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 272258 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@12c2584[collection1] realtime]
[junit4] 2> 272258 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 272296 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 272296 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 272310 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11d0686[collection1] realtime]
[junit4] 2> 272311 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 272381 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 272381 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 272387 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@218956[collection1] realtime]
[junit4] 2> 272394 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 272429 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 272429 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 272436 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cb8cec[collection1] realtime]
[junit4] 2> 272437 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 272551 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 272551 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 272558 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e36d49[collection1] realtime]
[junit4] 2> 272570 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 272581 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1603268270428782592), 1 (1603268270429831168), 2 (1603268270430879744), 3 (1603268270432976896), 4 (1603268270432976897), 5 (1603268270432976898), 6 (1603268270437171200), 7 (1603268270438219776), 8 (1603268270438219777), 9 (1603268270442414080), ... (150 adds)]} 0 379
[junit4] 2> 272783 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[0 (-1603268271036956672), 1 (-1603268271036956673), 2 (-1603268271036956674), 3 (-1603268271038005248), 4 (-1603268271038005249), 5 (-1603268271038005250), 6 (-1603268271038005251), 7 (-1603268271038005252), 8 (-1603268271038005253), 9 (-1603268271038005254), ... (15 deletes)]} 0 1
[junit4] 2> 272984 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[15 (-1603268271248769024), 16 (-1603268271248769025), 17 (-1603268271248769026), 18 (-1603268271248769027), 19 (-1603268271248769028), 20 (-1603268271248769029), 21 (-1603268271248769030), 22 (-1603268271248769031), 23 (-1603268271248769032), 24 (-1603268271248769033), ... (15 deletes)]} 0 0
[junit4] 2> 273186 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[30 (-1603268271459532800), 31 (-1603268271459532801), 32 (-1603268271459532802), 33 (-1603268271459532803), 34 (-1603268271459532804), 35 (-1603268271459532805), 36 (-1603268271459532806), 37 (-1603268271459532807), 38 (-1603268271459532808), 39 (-1603268271459532809), ... (15 deletes)]} 0 1
[junit4] 2> 273186 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 273186 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 273190 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2148c9[collection1] realtime]
[junit4] 2> 273191 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 273237 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[45 (-1603268271513010176), 46 (-1603268271514058752), 47 (-1603268271514058753), 48 (-1603268271514058754), 49 (-1603268271514058755), 50 (-1603268271514058756), 51 (-1603268271514058757), 52 (-1603268271514058758), 53 (-1603268271514058759), 54 (-1603268271514058760), ... (15 deletes)]} 0 1
[junit4] 2> 273439 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[60 (-1603268271724822528), 61 (-1603268271724822529), 62 (-1603268271724822530), 63 (-1603268271724822531), 64 (-1603268271724822532), 65 (-1603268271724822533), 66 (-1603268271724822534), 67 (-1603268271724822535), 68 (-1603268271724822536), 69 (-1603268271724822537), ... (15 deletes)]} 0 0
[junit4] 2> 273640 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[75 (-1603268271935586304), 76 (-1603268271935586305), 77 (-1603268271936634880), 78 (-1603268271936634881), 79 (-1603268271936634882), 80 (-1603268271936634883), 81 (-1603268271936634884), 82 (-1603268271936634885), 83 (-1603268271936634886), 84 (-1603268271936634887), ... (15 deletes)]} 0 0
[junit4] 2> 273841 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[90 (-1603268272147398656), 91 (-1603268272147398657), 92 (-1603268272147398658), 93 (-1603268272147398659), 94 (-1603268272147398660), 95 (-1603268272147398661), 96 (-1603268272147398662), 97 (-1603268272147398663), 98 (-1603268272147398664), 99 (-1603268272147398665), ... (15 deletes)]} 0 1
[junit4] 2> 273842 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 273842 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> 273843 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@109f469[collection1] realtime]
[junit4] 2> 273844 INFO (commitScheduler-1098-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 273893 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[105 (-1603268272200876032), 106 (-1603268272201924608), 107 (-1603268272201924609), 108 (-1603268272201924610), 109 (-1603268272201924611), 110 (-1603268272201924612), 111 (-1603268272201924613), 112 (-1603268272201924614), 113 (-1603268272201924615), 114 (-1603268272201924616), ... (15 deletes)]} 0 1
[junit4] 2> 274095 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[120 (-1603268272412688384), 121 (-1603268272412688385), 122 (-1603268272412688386), 123 (-1603268272412688387), 124 (-1603268272412688388), 125 (-1603268272412688389), 126 (-1603268272412688390), 127 (-1603268272412688391), 128 (-1603268272412688392), 129 (-1603268272412688393), ... (15 deletes)]} 0 0
[junit4] 2> 274296 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[135 (-1603268272623452160), 136 (-1603268272623452161), 137 (-1603268272623452162), 138 (-1603268272623452163), 139 (-1603268272623452164), 140 (-1603268272623452165), 141 (-1603268272623452166), 142 (-1603268272623452167), 143 (-1603268272623452168), 144 (-1603268272623452169), ... (15 deletes)]} 0 0
[junit4] 2> 274496 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteTest
[junit4] 2> 274496 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 274496 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=13873860
[junit4] 2> 274497 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 274497 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@154ead7: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1dd56c
[junit4] 2> 274501 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 274501 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14f1504: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5592a3
[junit4] 2> 274503 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 274503 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@dd3f1b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@12a0e2c
[junit4] 2> 274504 INFO (coreCloseExecutor-1100-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@fdf883
[junit4] 2> 274504 INFO (coreCloseExecutor-1100-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=fdf883
[junit4] 2> 274504 INFO (coreCloseExecutor-1100-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12ac915: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f19c7a
[junit4] 2> 274510 INFO (coreCloseExecutor-1100-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 274510 INFO (coreCloseExecutor-1100-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@22ad17 commitCommandVersion:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=55A691B33D8C39F0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 2.88s J0 | MaxSizeAutoCommitTest.deleteTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001/tlog/tlog.0000000000000000005, tlog size: 1276
[junit4] > at __randomizedtesting.SeedInfo.seed([55A691B33D8C39F0:45E8744C46220001]:0)
[junit4] > at org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:379)
[junit4] > at org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:200)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 274517 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRedundantDeletes
[junit4] 2> 274517 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 274517 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 274533 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 274540 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 274588 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 274690 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 274690 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 274701 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 274707 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 274707 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b3360d
[junit4] 2> 274708 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 274723 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 274730 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 274788 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 274792 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 274792 INFO (coreLoadExecutor-1110-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@b3360d
[junit4] 2> 274792 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 274792 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001/init-core-data-001/]
[junit4] 2> 274793 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=6.2529296875, floorSegmentMB=0.97265625, forceMergeDeletesPctAllowed=0.4385253564230662, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 274795 WARN (coreLoadExecutor-1110-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> 274819 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 274819 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 274819 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 274819 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 274820 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=68.46484375, floorSegmentMB=1.2607421875, forceMergeDeletesPctAllowed=28.30374423521128, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6072955310095851
[junit4] 2> 274820 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@48c50f[collection1] main]
[junit4] 2> 274820 WARN (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 274821 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 274821 INFO (coreLoadExecutor-1110-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1603268273175003136
[junit4] 2> 274821 INFO (searcherExecutor-1111-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@48c50f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 274823 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 274850 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 274850 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 274855 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15dbc51[collection1] realtime]
[junit4] 2> 274855 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 274877 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 274877 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 274882 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a6d109[collection1] realtime]
[junit4] 2> 274882 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 274916 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 274916 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 274919 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ed6ba[collection1] realtime]
[junit4] 2> 274921 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 274942 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 274942 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 274945 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53fad0[collection1] realtime]
[junit4] 2> 274946 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 274980 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 274980 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 274983 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@88f55f[collection1] realtime]
[junit4] 2> 274984 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 274992 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1603268273178148864), 1 (1603268273179197440), 2 (1603268273179197441), 3 (1603268273181294592), 4 (1603268273182343168), 5 (1603268273182343169), 6 (1603268273184440320), 7 (1603268273185488896), 8 (1603268273185488897), 9 (1603268273187586048), ... (150 adds)]} 0 168
[junit4] 2> 275394 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273774788608)]} 0 200
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885760)]} 0 202
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885761)]} 0 202
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885762)]} 0 202
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885763)]} 0 202
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885764)]} 0 202
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885765)]} 0 202
[junit4] 2> 275395 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885766)]} 0 202
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273776885767)]} 0 202
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934336)]} 0 202
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934337)]} 0 202
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934338)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934339)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934340)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934341)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934342)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934343)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934344)]} 0 203
[junit4] 2> 275396 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934345)]} 0 203
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273777934346)]} 0 203
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982912)]} 0 203
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982913)]} 0 203
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982914)]} 0 203
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982915)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982916)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982917)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982918)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982919)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982920)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982921)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982922)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982923)]} 0 204
[junit4] 2> 275397 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273778982924)]} 0 204
[junit4] 2> 275398 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273780031488)]} 0 204
[junit4] 2> 275398 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 275398 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273780031489)]} 0 205
[junit4] 2> 275398 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273780031490)]} 0 205
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080064)]} 0 205
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080065)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080066)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080067)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080068)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080069)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080070)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080071)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080072)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080073)]} 0 206
[junit4] 2> 275399 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273781080074)]} 0 206
[junit4] 2> 275400 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273782128640)]} 0 206
[junit4] 2> 275400 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273782128641)]} 0 206
[junit4] 2> 275400 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273782128642)]} 0 206
[junit4] 2> 275401 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c8c2c[collection1] realtime]
[junit4] 2> 275402 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 275600 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273991843840)]} 0 407
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892416)]} 0 407
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892417)]} 0 407
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892418)]} 0 407
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892419)]} 0 407
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892420)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892421)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892422)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892423)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892424)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892425)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892426)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892427)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892428)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892429)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892430)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892431)]} 0 408
[junit4] 2> 275601 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273992892432)]} 0 408
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940992)]} 0 408
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940993)]} 0 408
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940994)]} 0 408
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940995)]} 0 408
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940996)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940997)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940998)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993940999)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941000)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941001)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941002)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941003)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941004)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941005)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941006)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941007)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941008)]} 0 409
[junit4] 2> 275602 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273993941009)]} 0 409
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989568)]} 0 409
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989569)]} 0 409
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989570)]} 0 409
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989571)]} 0 409
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989572)]} 0 409
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989573)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989574)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989575)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989576)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989577)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989578)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989579)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989580)]} 0 410
[junit4] 2> 275603 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268273994989581)]} 0 410
[junit4] 2> 275603 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 275603 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 275605 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@d285d7[collection1] realtime]
[junit4] 2> 275606 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 275654 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274047418368)]} 0 461
[junit4] 2> 275654 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274048466944)]} 0 461
[junit4] 2> 275654 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274048466945)]} 0 461
[junit4] 2> 275654 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274048466946)]} 0 461
[junit4] 2> 275654 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274048466947)]} 0 461
[junit4] 2> 275654 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274048466948)]} 0 461
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515520)]} 0 461
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515521)]} 0 461
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515522)]} 0 461
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515523)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515524)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515525)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515526)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515527)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515528)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515529)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515530)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515531)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515532)]} 0 462
[junit4] 2> 275655 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515533)]} 0 462
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274049515534)]} 0 462
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564096)]} 0 462
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564097)]} 0 462
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564098)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564099)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564100)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564101)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564102)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564103)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564104)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564105)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564106)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564107)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564108)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564109)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564110)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564111)]} 0 463
[junit4] 2> 275656 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564112)]} 0 463
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274050564113)]} 0 463
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612672)]} 0 463
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612673)]} 0 463
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612674)]} 0 463
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612675)]} 0 463
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612676)]} 0 464
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612677)]} 0 464
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612678)]} 0 464
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612679)]} 0 464
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612680)]} 0 464
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612681)]} 0 464
[junit4] 2> 275657 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274051612682)]} 0 464
[junit4] 2> 275658 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 275658 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cb458d commitCommandVersion:0
[junit4] 2> 275659 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11be985[collection1] realtime]
[junit4] 2> 275660 INFO (commitScheduler-1114-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274104041472)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090048)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090049)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090050)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090051)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090052)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090053)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090054)]} 0 515
[junit4] 2> 275708 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090055)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274105090056)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138624)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138625)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138626)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138627)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138628)]} 0 515
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138629)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138630)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138631)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138632)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138633)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138634)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138635)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138636)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138637)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138638)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138639)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138640)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-1603268274106138641)]} 0 516
[junit4] 2> 275709 INFO (TEST-MaxSizeAutoCommitTest.testRedundantDeletes-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[100 (-160326827
[...truncated too long message...]
ndexWriter@17ca591 commitCommandVersion:0
[junit4] 2> 340363 INFO (commitScheduler-1194-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ad200f[collection1] realtime]
[junit4] 2> 340363 INFO (commitScheduler-1194-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 340363 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 (1603268341888188416), 941 (1603268341890285568), 942 (1603268341890285569), 943 (1603268341890285570), 944 (1603268341892382720), 945 (1603268341892382721), 946 (1603268341892382722), 947 (1603268341894479872), 948 (1603268341894479873), 949 (1603268341894479874), ... (20 adds)]} 0 12
[junit4] 2> 340564 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] 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=10424
[junit4] 2> 340593 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 (1603268342111535104), 961 (1603268342113632256), 962 (1603268342130409472), 963 (1603268342131458048), 964 (1603268342131458049), 965 (1603268342132506624), 966 (1603268342132506625), 967 (1603268342132506626), 968 (1603268342134603776), 969 (1603268342134603777), ... (20 adds)]} 0 28
[junit4] 2> 340793 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] 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=10653
[junit4] 2> 340805 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 (1603268342351659008), 981 (1603268342352707584), 982 (1603268342352707585), 983 (1603268342353756160), 984 (1603268342353756161), 985 (1603268342353756162), 986 (1603268342355853312), 987 (1603268342355853313), 988 (1603268342355853314), 989 (1603268342357950464), ... (20 adds)]} 0 11
[junit4] 2> 341006 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] 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=10865
[junit4] 2> 341006 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 341006 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17ca591 commitCommandVersion:0
[junit4] 2> 341009 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b828c[collection1] main]
[junit4] 2> 341010 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 341011 INFO (searcherExecutor-1191-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b828c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9c(8.0.0):c962) Uninverting(_9d(8.0.0):c3) Uninverting(_9e(8.0.0):c3) Uninverting(_9f(8.0.0):c3) Uninverting(_9g(8.0.0):c3) Uninverting(_9h(8.0.0):c3) Uninverting(_9i(8.0.0):c3) Uninverting(_9j(8.0.0):c3) Uninverting(_9k(8.0.0):c3) Uninverting(_9l(8.0.0):c3) Uninverting(_9m(8.0.0):c3) Uninverting(_9n(8.0.0):c3) Uninverting(_9o(8.0.0):c3) Uninverting(_9p(8.0.0):c2)))}
[junit4] 2> 341061 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=1000 status=0 QTime=10921
[junit4] 2> 341062 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending endToEndTest {seed=[55A691B33D8C39F0:860FAED75CE0687]}
[junit4] 2> 341063 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 341063 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=10384523
[junit4] 2> 341063 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 341063 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ca08a5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5d2225
[junit4] 2> 341067 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 341067 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@109673f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5592a3
[junit4] 2> 341070 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 341070 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[55A691B33D8C39F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d5788: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@12a0e2c
[junit4] 2> 341070 INFO (coreCloseExecutor-1196-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2e92f
[junit4] 2> 341070 INFO (coreCloseExecutor-1196-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2e92f
[junit4] 2> 341070 INFO (coreCloseExecutor-1196-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12423b9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@86a2ac
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.MaxSizeAutoCommitTest_55A691B33D8C39F0-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=PostingsFormat(name=Asserting), id=BlockTreeOrds(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), multiDefault=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Asserting), range_facet_l=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=569, maxMBSortInHeap=6.100894550735408, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1b15889), locale=it-CH, timezone=America/Indiana/Indianapolis
[junit4] 2> NOTE: Linux 4.13.0-41-generic i386/Oracle Corporation 1.8.0_172 (32-bit)/cpus=8,threads=1,free=325647480,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [HdfsSyncSliceTest, TestNodeAddedTrigger, TestWordDelimiterFilterFactory, DistributedFacetExistsSmallTest, ScriptEngineTest, DocExpirationUpdateProcessorFactoryTest, MBeansHandlerTest, AnalysisErrorHandlingTest, TestNoOpRegenerator, DistributedFacetPivotSmallAdvancedTest, TestMacroExpander, TestLeaderElectionWithEmptyReplica, TestSlowCompositeReaderWrapper, TestMergePolicyConfig, SolrMetricsIntegrationTest, TestCollationField, TestNumericTerms64, CustomCollectionTest, EnumFieldTest, TestCollationFieldDocValues, ImplicitSnitchTest, TestBulkSchemaConcurrent, MetricUtilsTest, TestComponentsName, SortSpecParsingTest, SearchRateTriggerTest, TestSchemaManager, AsyncCallRequestStatusResponseTest, MaxSizeAutoCommitTest]
[junit4] Completed [95/817 (1!)] on J0 in 72.25s, 8 tests, 1 failure <<< FAILURES!
[...truncated 48898 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22250/consoleText
[repro] Revision: 228a84fd6db3ef5fc1624d69e1c82a1f02c51352
[repro] Ant options: "-Dargs=-server -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=55A691B33D8C39F0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] MaxSizeAutoCommitTest
[repro] ant compile-test
[...truncated 3300 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.MaxSizeAutoCommitTest" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseG1GC" -Dtests.seed=55A691B33D8C39F0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it-CH -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 106 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.MaxSizeAutoCommitTest
[repro] Exiting with code 0
[...truncated 40 lines...]