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/05/09 02:52:18 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-11-ea+5) - Build #
21979 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21979/
Java: 64bit/jdk-11-ea+5 -XX:+UseCompressedOops -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/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
Stack Trace:
java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
at __randomizedtesting.SeedInfo.seed([988AEB31DED7DE72:88C40ECEA579E783]: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 java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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 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.base/java.lang.Thread.run(Thread.java:841)
Build Log:
[...truncated 13636 lines...]
[junit4] Suite: org.apache.solr.update.MaxSizeAutoCommitTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001
[junit4] 2> 1205701 WARN (SUITE-MaxSizeAutoCommitTest-seed#[988AEB31DED7DE72]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1205701 INFO (SUITE-MaxSizeAutoCommitTest-seed#[988AEB31DED7DE72]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1205702 INFO (SUITE-MaxSizeAutoCommitTest-seed#[988AEB31DED7DE72]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1205702 INFO (SUITE-MaxSizeAutoCommitTest-seed#[988AEB31DED7DE72]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1205703 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteTest
[junit4] 2> 1205703 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1205703 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] 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> 1205716 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1205726 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1205791 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1205821 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1205831 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1205831 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1205832 INFO (coreLoadExecutor-4436-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1205833 INFO (coreLoadExecutor-4436-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> 1205842 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1205848 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1205893 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1205929 INFO (coreLoadExecutor-4436-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> 1205930 INFO (coreLoadExecutor-4436-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@2cec92c4
[junit4] 2> 1205930 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1205930 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001/]
[junit4] 2> 1205931 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=78.82421875, floorSegmentMB=1.728515625, forceMergeDeletesPctAllowed=7.70911407292422, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2389996576467656
[junit4] 2> 1205972 WARN (coreLoadExecutor-4436-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> 1206007 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1206007 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1206008 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1206008 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1206009 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12102141144375525]
[junit4] 2> 1206009 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a3b8dde[collection1] main]
[junit4] 2> 1206009 WARN (coreLoadExecutor-4436-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> 1206010 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1206010 INFO (searcherExecutor-4437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a3b8dde[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1206010 INFO (coreLoadExecutor-4436-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1599951041173913600
[junit4] 2> 1206012 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1206017 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1206017 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5785a73c commitCommandVersion:0
[junit4] 2> 1206022 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7969a417[collection1] realtime]
[junit4] 2> 1206023 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1206023 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1206023 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5785a73c commitCommandVersion:0
[junit4] 2> 1206023 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@fcfe6c0[collection1] realtime]
[junit4] 2> 1206023 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1206032 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1206032 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5785a73c commitCommandVersion:0
[junit4] 2> 1206033 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1599951041178107904), 1 (1599951041179156480), 2 (1599951041179156481), 3 (1599951041179156482), 4 (1599951041179156483), 5 (1599951041179156484), 6 (1599951041179156485), 7 (1599951041179156486), 8 (1599951041179156487), 9 (1599951041179156488), ... (150 adds)]} 0 20
[junit4] 2> 1206037 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@114508e0[collection1] realtime]
[junit4] 2> 1206038 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1206235 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[0 (-1599951041409843200), 1 (-1599951041409843201), 2 (-1599951041409843202), 3 (-1599951041409843203), 4 (-1599951041409843204), 5 (-1599951041409843205), 6 (-1599951041409843206), 7 (-1599951041409843207), 8 (-1599951041409843208), 9 (-1599951041409843209), ... (15 deletes)]} 0 0
[junit4] 2> 1206436 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[15 (-1599951041620606976), 16 (-1599951041620606977), 17 (-1599951041620606978), 18 (-1599951041620606979), 19 (-1599951041620606980), 20 (-1599951041620606981), 21 (-1599951041620606982), 22 (-1599951041620606983), 23 (-1599951041620606984), 24 (-1599951041620606985), ... (15 deletes)]} 0 0
[junit4] 2> 1206637 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[30 (-1599951041831370752), 31 (-1599951041831370753), 32 (-1599951041831370754), 33 (-1599951041831370755), 34 (-1599951041831370756), 35 (-1599951041831370757), 36 (-1599951041831370758), 37 (-1599951041831370759), 38 (-1599951041831370760), 39 (-1599951041831370761), ... (15 deletes)]} 0 0
[junit4] 2> 1206838 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[45 (-1599951042042134528), 46 (-1599951042042134529), 47 (-1599951042042134530), 48 (-1599951042042134531), 49 (-1599951042042134532), 50 (-1599951042042134533), 51 (-1599951042042134534), 52 (-1599951042042134535), 53 (-1599951042042134536), 54 (-1599951042042134537), ... (15 deletes)]} 0 0
[junit4] 2> 1206839 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1206839 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5785a73c commitCommandVersion:0
[junit4] 2> 1206840 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13f91bf2[collection1] realtime]
[junit4] 2> 1206840 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1206889 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[60 (-1599951042095611904), 61 (-1599951042095611905), 62 (-1599951042095611906), 63 (-1599951042095611907), 64 (-1599951042095611908), 65 (-1599951042095611909), 66 (-1599951042095611910), 67 (-1599951042095611911), 68 (-1599951042095611912), 69 (-1599951042095611913), ... (15 deletes)]} 0 1
[junit4] 2> 1207090 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[75 (-1599951042306375680), 76 (-1599951042306375681), 77 (-1599951042306375682), 78 (-1599951042306375683), 79 (-1599951042306375684), 80 (-1599951042306375685), 81 (-1599951042306375686), 82 (-1599951042306375687), 83 (-1599951042306375688), 84 (-1599951042306375689), ... (15 deletes)]} 0 0
[junit4] 2> 1207291 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[90 (-1599951042517139456), 91 (-1599951042517139457), 92 (-1599951042517139458), 93 (-1599951042517139459), 94 (-1599951042517139460), 95 (-1599951042517139461), 96 (-1599951042517139462), 97 (-1599951042517139463), 98 (-1599951042517139464), 99 (-1599951042517139465), ... (15 deletes)]} 0 0
[junit4] 2> 1207492 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[105 (-1599951042727903232), 106 (-1599951042727903233), 107 (-1599951042727903234), 108 (-1599951042727903235), 109 (-1599951042727903236), 110 (-1599951042727903237), 111 (-1599951042727903238), 112 (-1599951042727903239), 113 (-1599951042727903240), 114 (-1599951042727903241), ... (15 deletes)]} 0 0
[junit4] 2> 1207493 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1207493 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5785a73c commitCommandVersion:0
[junit4] 2> 1207494 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d4925be[collection1] realtime]
[junit4] 2> 1207495 INFO (commitScheduler-4440-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1207543 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[120 (-1599951042781380608), 121 (-1599951042781380609), 122 (-1599951042781380610), 123 (-1599951042781380611), 124 (-1599951042781380612), 125 (-1599951042781380613), 126 (-1599951042781380614), 127 (-1599951042781380615), 128 (-1599951042781380616), 129 (-1599951042781380617), ... (15 deletes)]} 0 0
[junit4] 2> 1207744 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[135 (-1599951042992144384), 136 (-1599951042992144385), 137 (-1599951042992144386), 138 (-1599951042992144387), 139 (-1599951042992144388), 140 (-1599951042992144389), 141 (-1599951042992144390), 142 (-1599951042992144391), 143 (-1599951042992144392), 144 (-1599951042992144393), ... (15 deletes)]} 0 0
[junit4] 2> 1207945 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteTest
[junit4] 2> 1207945 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1207945 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1673842539
[junit4] 2> 1207945 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1207945 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b49cd41: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5ff21135
[junit4] 2> 1207950 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1207950 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d5f7920: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7bd65aeb
[junit4] 2> 1207954 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1207954 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3e4bf6b7: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@c1b6172
[junit4] 2> 1207954 INFO (coreCloseExecutor-4442-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7048769a
[junit4] 2> 1207954 INFO (coreCloseExecutor-4442-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7048769a
[junit4] 2> 1207955 INFO (coreCloseExecutor-4442-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@375a436: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@796b8f9a
[junit4] 2> 1207963 INFO (coreCloseExecutor-4442-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 1207963 INFO (coreCloseExecutor-4442-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5785a73c commitCommandVersion:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=988AEB31DED7DE72 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=is-IS -Dtests.timezone=EST5EDT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 2.27s J1 | 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/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
[junit4] > at __randomizedtesting.SeedInfo.seed([988AEB31DED7DE72:88C40ECEA579E783]: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.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:841)
[junit4] 2> 1207968 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###Starting endToEndTest {seed=[988AEB31DED7DE72:82DC84073E65F970]}
[junit4] 2> 1207969 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1207969 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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> 1207982 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1207993 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1208060 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1208092 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1208098 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1208098 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1208099 INFO (coreLoadExecutor-4449-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1208099 INFO (coreLoadExecutor-4449-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> 1208114 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1208125 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1208216 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1208224 INFO (coreLoadExecutor-4449-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> 1208224 INFO (coreLoadExecutor-4449-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@2cec92c4
[junit4] 2> 1208224 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1208224 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001/]
[junit4] 2> 1208226 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=78.82421875, floorSegmentMB=1.728515625, forceMergeDeletesPctAllowed=7.70911407292422, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2389996576467656
[junit4] 2> 1208227 WARN (coreLoadExecutor-4449-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> 1208258 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1208258 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1208258 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1208258 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1208259 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12102141144375525]
[junit4] 2> 1208259 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@757b92a1[collection1] main]
[junit4] 2> 1208260 WARN (coreLoadExecutor-4449-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> 1208260 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1208261 INFO (searcherExecutor-4450-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@757b92a1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1208261 INFO (coreLoadExecutor-4449-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1599951043534258176
[junit4] 2> 1208263 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1208266 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1599951043537403904), 1 (1599951043538452480), 2 (1599951043538452481), 3 (1599951043538452482), 4 (1599951043538452483), 5 (1599951043538452484), 6 (1599951043538452485), 7 (1599951043538452486), 8 (1599951043538452487), 9 (1599951043538452488), ... (20 adds)]} 0 1
[junit4] 2> 1208467 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=202
[junit4] 2> 1208469 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1599951043751313408), 21 (1599951043751313409), 22 (1599951043751313410), 23 (1599951043751313411), 24 (1599951043751313412), 25 (1599951043751313413), 26 (1599951043751313414), 27 (1599951043751313415), 28 (1599951043751313416), 29 (1599951043751313417), ... (20 adds)]} 0 0
[junit4] 2> 1208670 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=405
[junit4] 2> 1208671 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1599951043963125760), 41 (1599951043963125761), 42 (1599951043963125762), 43 (1599951043963125763), 44 (1599951043963125764), 45 (1599951043963125765), 46 (1599951043963125766), 47 (1599951043963125767), 48 (1599951043963125768), 49 (1599951043963125769), ... (20 adds)]} 0 0
[junit4] 2> 1208871 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=607
[junit4] 2> 1208872 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1599951044174938112), 61 (1599951044174938113), 62 (1599951044174938114), 63 (1599951044174938115), 64 (1599951044174938116), 65 (1599951044174938117), 66 (1599951044174938118), 67 (1599951044174938119), 68 (1599951044174938120), 69 (1599951044174938121), ... (20 adds)]} 0 0
[junit4] 2> 1209073 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=809
[junit4] 2> 1209074 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1599951044385701888), 81 (1599951044385701889), 82 (1599951044386750464), 83 (1599951044386750465), 84 (1599951044386750466), 85 (1599951044386750467), 86 (1599951044386750468), 87 (1599951044386750469), 88 (1599951044386750470), 89 (1599951044386750471), ... (20 adds)]} 0 0
[junit4] 2> 1209276 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1012
[junit4] 2> 1209277 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1599951044598562816), 101 (1599951044598562817), 102 (1599951044598562818), 103 (1599951044598562819), 104 (1599951044598562820), 105 (1599951044599611392), 106 (1599951044599611393), 107 (1599951044599611394), 108 (1599951044599611395), 109 (1599951044599611396), ... (20 adds)]} 0 0
[junit4] 2> 1209478 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1213
[junit4] 2> 1209479 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1599951044810375168), 121 (1599951044810375169), 122 (1599951044810375170), 123 (1599951044810375171), 124 (1599951044810375172), 125 (1599951044810375173), 126 (1599951044810375174), 127 (1599951044810375175), 128 (1599951044810375176), 129 (1599951044810375177), ... (20 adds)]} 0 1
[junit4] 2> 1209480 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1209480 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1209487 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@47477be3[collection1] realtime]
[junit4] 2> 1209487 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1209529 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1265
[junit4] 2> 1209531 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1599951044864901120), 141 (1599951044865949696), 142 (1599951044865949697), 143 (1599951044865949698), 144 (1599951044865949699), 145 (1599951044865949700), 146 (1599951044865949701), 147 (1599951044865949702), 148 (1599951044865949703), 149 (1599951044865949704), ... (20 adds)]} 0 1
[junit4] 2> 1209732 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1468
[junit4] 2> 1209733 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1599951045076713472), 161 (1599951045076713473), 162 (1599951045077762048), 163 (1599951045077762049), 164 (1599951045077762050), 165 (1599951045077762051), 166 (1599951045077762052), 167 (1599951045077762053), 168 (1599951045077762054), 169 (1599951045077762055), ... (20 adds)]} 0 0
[junit4] 2> 1209934 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1670
[junit4] 2> 1209935 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1599951045288525824), 181 (1599951045288525825), 182 (1599951045288525826), 183 (1599951045288525827), 184 (1599951045288525828), 185 (1599951045288525829), 186 (1599951045289574400), 187 (1599951045289574401), 188 (1599951045289574402), 189 (1599951045289574403), ... (20 adds)]} 0 0
[junit4] 2> 1210136 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1871
[junit4] 2> 1210137 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 (1599951045500338176), 201 (1599951045500338177), 202 (1599951045500338178), 203 (1599951045500338179), 204 (1599951045500338180), 205 (1599951045500338181), 206 (1599951045500338182), 207 (1599951045500338183), 208 (1599951045500338184), 209 (1599951045500338185), ... (20 adds)]} 0 0
[junit4] 2> 1210338 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=2073
[junit4] 2> 1210339 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 (1599951045712150528), 221 (1599951045712150529), 222 (1599951045712150530), 223 (1599951045712150531), 224 (1599951045712150532), 225 (1599951045712150533), 226 (1599951045712150534), 227 (1599951045712150535), 228 (1599951045713199104), 229 (1599951045713199105), ... (20 adds)]} 0 0
[junit4] 2> 1210540 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=2276
[junit4] 2> 1210541 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 (1599951045923962880), 241 (1599951045923962881), 242 (1599951045923962882), 243 (1599951045923962883), 244 (1599951045923962884), 245 (1599951045923962885), 246 (1599951045923962886), 247 (1599951045923962887), 248 (1599951045923962888), 249 (1599951045923962889), ... (20 adds)]} 0 0
[junit4] 2> 1210741 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=2477
[junit4] 2> 1210742 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 (1599951046135775232), 261 (1599951046135775233), 262 (1599951046135775234), 263 (1599951046135775235), 264 (1599951046135775236), 265 (1599951046135775237), 266 (1599951046135775238), 267 (1599951046135775239), 268 (1599951046135775240), 269 (1599951046135775241), ... (20 adds)]} 0 0
[junit4] 2> 1210743 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1210743 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1210746 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ef7af53[collection1] realtime]
[junit4] 2> 1210747 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1210793 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=2528
[junit4] 2> 1210794 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 (1599951046189252608), 281 (1599951046190301184), 282 (1599951046190301185), 283 (1599951046190301186), 284 (1599951046190301187), 285 (1599951046190301188), 286 (1599951046190301189), 287 (1599951046190301190), 288 (1599951046190301191), 289 (1599951046190301192), ... (20 adds)]} 0 1
[junit4] 2> 1210995 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=2731
[junit4] 2> 1210996 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 (1599951046401064960), 301 (1599951046401064961), 302 (1599951046401064962), 303 (1599951046401064963), 304 (1599951046401064964), 305 (1599951046401064965), 306 (1599951046401064966), 307 (1599951046401064967), 308 (1599951046401064968), 309 (1599951046402113536), ... (20 adds)]} 0 0
[junit4] 2> 1211197 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=2932
[junit4] 2> 1211198 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 (1599951046612877312), 321 (1599951046612877313), 322 (1599951046612877314), 323 (1599951046612877315), 324 (1599951046612877316), 325 (1599951046612877317), 326 (1599951046612877318), 327 (1599951046612877319), 328 (1599951046612877320), 329 (1599951046612877321), ... (20 adds)]} 0 0
[junit4] 2> 1211398 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=3134
[junit4] 2> 1211399 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 (1599951046824689664), 341 (1599951046824689665), 342 (1599951046824689666), 343 (1599951046824689667), 344 (1599951046824689668), 345 (1599951046824689669), 346 (1599951046824689670), 347 (1599951046824689671), 348 (1599951046824689672), 349 (1599951046824689673), ... (20 adds)]} 0 0
[junit4] 2> 1211600 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=3335
[junit4] 2> 1211600 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 (1599951047035453440), 361 (1599951047035453441), 362 (1599951047035453442), 363 (1599951047035453443), 364 (1599951047035453444), 365 (1599951047035453445), 366 (1599951047035453446), 367 (1599951047035453447), 368 (1599951047035453448), 369 (1599951047035453449), ... (20 adds)]} 0 0
[junit4] 2> 1211801 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=3537
[junit4] 2> 1211802 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 (1599951047246217216), 381 (1599951047246217217), 382 (1599951047246217218), 383 (1599951047246217219), 384 (1599951047246217220), 385 (1599951047247265792), 386 (1599951047247265793), 387 (1599951047247265794), 388 (1599951047247265795), 389 (1599951047247265796), ... (20 adds)]} 0 0
[junit4] 2> 1212003 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=3738
[junit4] 2> 1212004 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 (1599951047458029568), 401 (1599951047458029569), 402 (1599951047458029570), 403 (1599951047458029571), 404 (1599951047458029572), 405 (1599951047458029573), 406 (1599951047458029574), 407 (1599951047458029575), 408 (1599951047459078144), 409 (1599951047459078145), ... (20 adds)]} 0 1
[junit4] 2> 1212005 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1212005 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1212008 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5621a25a[collection1] realtime]
[junit4] 2> 1212008 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1212055 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=3790
[junit4] 2> 1212056 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 (1599951047512555520), 421 (1599951047513604096), 422 (1599951047513604097), 423 (1599951047513604098), 424 (1599951047513604099), 425 (1599951047513604100), 426 (1599951047513604101), 427 (1599951047513604102), 428 (1599951047513604103), 429 (1599951047513604104), ... (20 adds)]} 0 1
[junit4] 2> 1212257 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=3992
[junit4] 2> 1212257 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 (1599951047724367872), 441 (1599951047724367873), 442 (1599951047724367874), 443 (1599951047724367875), 444 (1599951047724367876), 445 (1599951047724367877), 446 (1599951047724367878), 447 (1599951047724367879), 448 (1599951047724367880), 449 (1599951047724367881), ... (20 adds)]} 0 0
[junit4] 2> 1212458 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=4194
[junit4] 2> 1212459 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 (1599951047935131648), 461 (1599951047935131649), 462 (1599951047935131650), 463 (1599951047935131651), 464 (1599951047935131652), 465 (1599951047935131653), 466 (1599951047935131654), 467 (1599951047935131655), 468 (1599951047935131656), 469 (1599951047935131657), ... (20 adds)]} 0 0
[junit4] 2> 1212659 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=4395
[junit4] 2> 1212660 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 (1599951048146944000), 481 (1599951048146944001), 482 (1599951048146944002), 483 (1599951048146944003), 484 (1599951048146944004), 485 (1599951048146944005), 486 (1599951048146944006), 487 (1599951048146944007), 488 (1599951048146944008), 489 (1599951048146944009), ... (20 adds)]} 0 0
[junit4] 2> 1212861 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=4597
[junit4] 2> 1212862 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1599951048357707776), 501 (1599951048357707777), 502 (1599951048357707778), 503 (1599951048357707779), 504 (1599951048357707780), 505 (1599951048357707781), 506 (1599951048357707782), 507 (1599951048357707783), 508 (1599951048357707784), 509 (1599951048358756352), ... (20 adds)]} 0 0
[junit4] 2> 1213063 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=4798
[junit4] 2> 1213063 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 (1599951048569520128), 521 (1599951048569520129), 522 (1599951048569520130), 523 (1599951048569520131), 524 (1599951048569520132), 525 (1599951048569520133), 526 (1599951048569520134), 527 (1599951048569520135), 528 (1599951048569520136), 529 (1599951048569520137), ... (20 adds)]} 0 0
[junit4] 2> 1213264 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=5000
[junit4] 2> 1213265 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 (1599951048780283904), 541 (1599951048780283905), 542 (1599951048781332480), 543 (1599951048781332481), 544 (1599951048781332482), 545 (1599951048781332483), 546 (1599951048781332484), 547 (1599951048781332485), 548 (1599951048781332486), 549 (1599951048781332487), ... (20 adds)]} 0 0
[junit4] 2> 1213266 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1213266 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1213270 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a496e9e[collection1] realtime]
[junit4] 2> 1213271 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1213316 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=5051
[junit4] 2> 1213317 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 (1599951048834809856), 561 (1599951048834809857), 562 (1599951048834809858), 563 (1599951048834809859), 564 (1599951048834809860), 565 (1599951048834809861), 566 (1599951048834809862), 567 (1599951048834809863), 568 (1599951048834809864), 569 (1599951048835858432), ... (20 adds)]} 0 1
[junit4] 2> 1213518 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=5253
[junit4] 2> 1213518 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 (1599951049046622208), 581 (1599951049046622209), 582 (1599951049046622210), 583 (1599951049046622211), 584 (1599951049046622212), 585 (1599951049046622213), 586 (1599951049046622214), 587 (1599951049046622215), 588 (1599951049046622216), 589 (1599951049046622217), ... (20 adds)]} 0 0
[junit4] 2> 1213719 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=5455
[junit4] 2> 1213886 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 (1599951049257385984), 601 (1599951049257385985), 602 (1599951049257385986), 603 (1599951049257385987), 604 (1599951049258434560), 605 (1599951049258434561), 606 (1599951049258434562), 607 (1599951049258434563), 608 (1599951049258434564), 609 (1599951049258434565), ... (20 adds)]} 0 167
[junit4] 2> 1214087 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=5823
[junit4] 2> 1214088 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 (1599951049644310528), 621 (1599951049644310529), 622 (1599951049644310530), 623 (1599951049644310531), 624 (1599951049644310532), 625 (1599951049644310533), 626 (1599951049644310534), 627 (1599951049644310535), 628 (1599951049644310536), 629 (1599951049644310537), ... (20 adds)]} 0 0
[junit4] 2> 1214289 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=6025
[junit4] 2> 1214289 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 (1599951049855074304), 641 (1599951049855074305), 642 (1599951049855074306), 643 (1599951049855074307), 644 (1599951049855074308), 645 (1599951049855074309), 646 (1599951049855074310), 647 (1599951049855074311), 648 (1599951049855074312), 649 (1599951049855074313), ... (20 adds)]} 0 0
[junit4] 2> 1214490 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=6226
[junit4] 2> 1214491 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 (1599951050065838080), 661 (1599951050066886656), 662 (1599951050066886657), 663 (1599951050066886658), 664 (1599951050066886659), 665 (1599951050066886660), 666 (1599951050066886661), 667 (1599951050066886662), 668 (1599951050066886663), 669 (1599951050066886664), ... (20 adds)]} 0 0
[junit4] 2> 1214693 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=6429
[junit4] 2> 1214694 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 (1599951050278699008), 681 (1599951050278699009), 682 (1599951050278699010), 683 (1599951050278699011), 684 (1599951050279747584), 685 (1599951050279747585), 686 (1599951050279747586), 687 (1599951050279747587), 688 (1599951050279747588), 689 (1599951050279747589), ... (20 adds)]} 0 0
[junit4] 2> 1214695 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1214695 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1214699 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5449df97[collection1] realtime]
[junit4] 2> 1214700 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1214744 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=6480
[junit4] 2> 1214746 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[700 (1599951050333224960), 701 (1599951050333224961), 702 (1599951050334273536), 703 (1599951050334273537), 704 (1599951050334273538), 705 (1599951050334273539), 706 (1599951050334273540), 707 (1599951050334273541), 708 (1599951050334273542), 709 (1599951050334273543), ... (20 adds)]} 0 1
[junit4] 2> 1214947 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=6683
[junit4] 2> 1214947 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[720 (1599951050545037312), 721 (1599951050545037313), 722 (1599951050545037314), 723 (1599951050545037315), 724 (1599951050545037316), 725 (1599951050545037317), 726 (1599951050545037318), 727 (1599951050545037319), 728 (1599951050545037320), 729 (1599951050545037321), ... (20 adds)]} 0 0
[junit4] 2> 1215148 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=6884
[junit4] 2> 1215149 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[740 (1599951050756849664), 741 (1599951050756849665), 742 (1599951050756849666), 743 (1599951050756849667), 744 (1599951050756849668), 745 (1599951050756849669), 746 (1599951050756849670), 747 (1599951050756849671), 748 (1599951050756849672), 749 (1599951050756849673), ... (20 adds)]} 0 0
[junit4] 2> 1215350 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=7086
[junit4] 2> 1215351 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[760 (1599951050967613440), 761 (1599951050967613441), 762 (1599951050967613442), 763 (1599951050967613443), 764 (1599951050967613444), 765 (1599951050967613445), 766 (1599951050967613446), 767 (1599951050967613447), 768 (1599951050967613448), 769 (1599951050967613449), ... (20 adds)]} 0 0
[junit4] 2> 1215551 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=7287
[junit4] 2> 1215552 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[780 (1599951051179425792), 781 (1599951051179425793), 782 (1599951051179425794), 783 (1599951051179425795), 784 (1599951051179425796), 785 (1599951051179425797), 786 (1599951051179425798), 787 (1599951051179425799), 788 (1599951051179425800), 789 (1599951051179425801), ... (20 adds)]} 0 0
[junit4] 2> 1215753 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=7489
[junit4] 2> 1215754 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[800 (1599951051390189568), 801 (1599951051390189569), 802 (1599951051390189570), 803 (1599951051390189571), 804 (1599951051390189572), 805 (1599951051390189573), 806 (1599951051390189574), 807 (1599951051390189575), 808 (1599951051390189576), 809 (1599951051390189577), ... (20 adds)]} 0 0
[junit4] 2> 1215954 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=7690
[junit4] 2> 1215955 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[820 (1599951051602001920), 821 (1599951051602001921), 822 (1599951051602001922), 823 (1599951051602001923), 824 (1599951051602001924), 825 (1599951051602001925), 826 (1599951051602001926), 827 (1599951051602001927), 828 (1599951051602001928), 829 (1599951051602001929), ... (20 adds)]} 0 0
[junit4] 2> 1215956 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1215956 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1215959 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@21110818[collection1] realtime]
[junit4] 2> 1215960 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1216006 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=7741
[junit4] 2> 1216007 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[840 (1599951051655479296), 841 (1599951051655479297), 842 (1599951051655479298), 843 (1599951051655479299), 844 (1599951051655479300), 845 (1599951051655479301), 846 (1599951051656527872), 847 (1599951051656527873), 848 (1599951051656527874), 849 (1599951051656527875), ... (20 adds)]} 0 0
[junit4] 2> 1216208 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=7943
[junit4] 2> 1216208 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[860 (1599951051867291648), 861 (1599951051867291649), 862 (1599951051867291650), 863 (1599951051867291651), 864 (1599951051867291652), 865 (1599951051867291653), 866 (1599951051867291654), 867 (1599951051867291655), 868 (1599951051867291656), 869 (1599951051867291657), ... (20 adds)]} 0 0
[junit4] 2> 1216409 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=8145
[junit4] 2> 1216410 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[880 (1599951052079104000), 881 (1599951052079104001), 882 (1599951052079104002), 883 (1599951052079104003), 884 (1599951052079104004), 885 (1599951052079104005), 886 (1599951052079104006), 887 (1599951052079104007), 888 (1599951052079104008), 889 (1599951052079104009), ... (20 adds)]} 0 0
[junit4] 2> 1216611 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=8347
[junit4] 2> 1216612 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[900 (1599951052289867776), 901 (1599951052289867777), 902 (1599951052289867778), 903 (1599951052289867779), 904 (1599951052289867780), 905 (1599951052289867781), 906 (1599951052289867782), 907 (1599951052289867783), 908 (1599951052289867784), 909 (1599951052289867785), ... (20 adds)]} 0 0
[junit4] 2> 1216812 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=8548
[junit4] 2> 1216813 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[920 (1599951052501680128), 921 (1599951052501680129), 922 (1599951052501680130), 923 (1599951052501680131), 924 (1599951052501680132), 925 (1599951052501680133), 926 (1599951052501680134), 927 (1599951052501680135), 928 (1599951052501680136), 929 (1599951052501680137), ... (20 adds)]} 0 0
[junit4] 2> 1217014 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=8750
[junit4] 2> 1217015 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 (1599951052712443904), 941 (1599951052712443905), 942 (1599951052712443906), 943 (1599951052712443907), 944 (1599951052712443908), 945 (1599951052712443909), 946 (1599951052712443910), 947 (1599951052712443911), 948 (1599951052712443912), 949 (1599951052712443913), ... (20 adds)]} 0 0
[junit4] 2> 1217215 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=8951
[junit4] 2> 1217216 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 (1599951052924256256), 961 (1599951052924256257), 962 (1599951052924256258), 963 (1599951052924256259), 964 (1599951052924256260), 965 (1599951052924256261), 966 (1599951052924256262), 967 (1599951052924256263), 968 (1599951052924256264), 969 (1599951052924256265), ... (20 adds)]} 0 0
[junit4] 2> 1217217 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1217217 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1217222 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@187054f[collection1] realtime]
[junit4] 2> 1217222 INFO (commitScheduler-4453-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1217267 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=9003
[junit4] 2> 1217269 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 (1599951052977733632), 981 (1599951052978782208), 982 (1599951052978782209), 983 (1599951052978782210), 984 (1599951052978782211), 985 (1599951052978782212), 986 (1599951052978782213), 987 (1599951052978782214), 988 (1599951052978782215), 989 (1599951052978782216), ... (20 adds)]} 0 1
[junit4] 2> 1217469 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=9205
[junit4] 2> 1217470 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1217470 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41fde66a commitCommandVersion:0
[junit4] 2> 1217527 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@675f417f[collection1] main]
[junit4] 2> 1217528 INFO (searcherExecutor-4450-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@675f417f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C140) Uninverting(_1(8.0.0):C140) Uninverting(_2(8.0.0):C140) Uninverting(_3(8.0.0):C140) Uninverting(_4(8.0.0):C140) Uninverting(_5(8.0.0):C140) Uninverting(_6(8.0.0):C140) Uninverting(_7(8.0.0):C20)))}
[junit4] 2> 1217529 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1217586 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=9322
[junit4] 2> 1217588 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###Ending endToEndTest {seed=[988AEB31DED7DE72:82DC84073E65F970]}
[junit4] 2> 1217588 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1217588 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1890680544
[junit4] 2> 1217588 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1217588 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35d19ecb: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4740a74e
[junit4] 2> 1217593 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1217593 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@560fa416: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7bd65aeb
[junit4] 2> 1217597 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1217597 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b1a048f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@c1b6172
[junit4] 2> 1217598 INFO (coreCloseExecutor-4455-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2062da63
[junit4] 2> 1217598 INFO (coreCloseExecutor-4455-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2062da63
[junit4] 2> 1217598 INFO (coreCloseExecutor-4455-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7da64297: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7b297a58
[junit4] 2> 1217633 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###Starting endToEndTest {seed=[988AEB31DED7DE72:368A38FB0AA7325C]}
[junit4] 2> 1217634 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1217634 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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> 1217650 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1217661 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1217737 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1217775 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1217784 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1217784 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cec92c4
[junit4] 2> 1217791 INFO (coreLoadExecutor-4462-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1217791 INFO (coreLoadExecutor-4462-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> 1217809 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1217821 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1217896 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1217903 INFO (coreLoadExecutor-4462-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> 1217904 INFO (coreLoadExecutor-4462-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@2cec92c4
[junit4] 2> 1217904 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1217904 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001/init-core-data-001/]
[junit4] 2> 1217905 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=78.82421875, floorSegmentMB=1.728515625, forceMergeDeletesPctAllowed=7.70911407292422, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2389996576467656
[junit4] 2> 1217907 WARN (coreLoadExecutor-4462-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> 1217939 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1217939 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1217940 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1217940 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1217941 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12102141144375525]
[junit4] 2> 1217941 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d72b761[collection1] main]
[junit4] 2> 1217941 WARN (coreLoadExecutor-4462-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> 1217942 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1217943 INFO (searcherExecutor-4463-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d72b761[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1217943 INFO (coreLoadExecutor-4462-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1599951053686571008
[junit4] 2> 1217945 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1217947 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1599951053689716736), 1 (1599951053690765312), 2 (1599951053690765313), 3 (1599951053690765314), 4 (1599951053690765315), 5 (1599951053690765316), 6 (1599951053690765317), 7 (1599951053690765318), 8 (1599951053690765319), 9 (1599951053690765320), ... (20 adds)]} 0 1
[junit4] 2> 1218148 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=202
[junit4] 2> 1218150 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1599951053902577664), 21 (1599951053902577665), 22 (1599951053902577666), 23 (1599951053902577667), 24 (1599951053902577668), 25 (1599951053902577669), 26 (1599951053902577670), 27 (1599951053902577671), 28 (1599951053902577672), 29 (1599951053902577673), ... (20 adds)]} 0 0
[junit4] 2> 1218350 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=404
[junit4] 2> 1218351 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1599951054114390016), 41 (1599951054114390017), 42 (1599951054114390018), 43 (1599951054114390019), 44 (1599951054114390020), 45 (1599951054114390021), 46 (1599951054114390022), 47 (1599951054114390023), 48 (1599951054114390024), 49 (1599951054114390025), ... (20 adds)]} 0 0
[junit4] 2> 1218552 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=606
[junit4] 2> 1218553 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1599951054325153792), 61 (1599951054325153793), 62 (1599951054325153794), 63 (1599951054325153795), 64 (1599951054325153796), 65 (1599951054325153797), 66 (1599951054326202368), 67 (1599951054326202369), 68 (1599951054326202370), 69 (1599951054326202371), ... (20 adds)]} 0 0
[junit4] 2> 1218754 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=808
[junit4] 2> 1218755 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1599951054536966144), 81 (1599951054536966145), 82 (1599951054536966146), 83 (1599951054536966147), 84 (1599951054536966148), 85 (1599951054536966149), 86 (1599951054536966150), 87 (1599951054536966151), 88 (1599951054536966152), 89 (1599951054536966153), ... (20 adds)]} 0 0
[junit4] 2> 1218956 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1010
[junit4] 2> 1218956 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1599951054748778496), 101 (1599951054748778497), 102 (1599951054748778498), 103 (1599951054748778499), 104 (1599951054748778500), 105 (1599951054748778501), 106 (1599951054748778502), 107 (1599951054748778503), 108 (1599951054748778504), 109 (1599951054748778505), ... (20 adds)]} 0 0
[junit4] 2> 1219157 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1211
[junit4] 2> 1219159 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1599951054959542272), 121 (1599951054960590848), 122 (1599951054960590849), 123 (1599951054960590850), 124 (1599951054960590851), 125 (1599951054960590852), 126 (1599951054960590853), 127 (1599951054960590854), 128 (1599951054960590855), 129 (1599951054960590856), ... (20 adds)]} 0 1
[junit4] 2> 1219159 INFO (commitScheduler-4466-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1219159 INFO (commitScheduler-4466-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@58060845 commitCommandVersion:0
[junit4] 2> 1219164 INFO (commitScheduler-4466-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1311f1d4[collection1] realtime]
[junit4] 2> 1219164 INFO (commitScheduler-4466-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1219209 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1263
[junit4] 2> 1219210 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1599951055014068224), 141 (1599951055015116800), 142 (1599951055015116801), 143 (1599951055015116802), 144 (1599951055015116803), 145 (1599951055015116804), 146 (1599951055015116805), 147 (1599951055015116806), 148 (1599951055015116807), 149 (1599951055015116808), ... (20 adds)]} 0 1
[junit4] 2> 1219411 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1465
[junit4] 2> 1219412 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1599951055226929152), 161 (1599951055226929153), 162 (1599951055226929154), 163 (1599951055226929155), 164 (1599951055226929156), 165 (1599951055226929157), 166 (1599951055226929158), 167 (1599951055226929159), 168 (1599951055226929160), 169 (1599951055226929161), ... (20 adds)]} 0 0
[junit4] 2> 1219613 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[988AEB31DED7DE72]) [ ] 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=1667
[junit4] 2> 1219614 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[9
[...truncated too long message...]
ommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[18 (1599951100373368832), 19 (1599951100373368833), 20 (1599951100373368834)]} 0 0
[junit4] 2> 1262668 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[21 (1599951100584132608), 22 (1599951100584132609), 23 (1599951100584132610)]} 0 0
[junit4] 2> 1262869 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[24 (1599951100794896384), 25 (1599951100794896385), 26 (1599951100794896386)]} 0 0
[junit4] 2> 1262870 INFO (commitScheduler-4531-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1262870 INFO (commitScheduler-4531-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@39247eb2 commitCommandVersion:0
[junit4] 2> 1262872 INFO (commitScheduler-4531-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ffa6198[collection1] realtime]
[junit4] 2> 1262872 INFO (commitScheduler-4531-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1262920 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[27 (1599951100847325184), 28 (1599951100848373760), 29 (1599951100848373761)]} 0 0
[junit4] 2> 1263121 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###Ending simpleTest
[junit4] 2> 1263121 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1263121 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=789509976
[junit4] 2> 1263121 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1263121 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@654db643: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4f5c10ba
[junit4] 2> 1263124 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1263124 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@604d373d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7bd65aeb
[junit4] 2> 1263126 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1263126 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[988AEB31DED7DE72]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b66fe4c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@c1b6172
[junit4] 2> 1263135 INFO (coreCloseExecutor-4533-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@621d2808
[junit4] 2> 1263135 INFO (coreCloseExecutor-4533-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=621d2808
[junit4] 2> 1263135 INFO (coreCloseExecutor-4533-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@54290554: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4899a59a
[junit4] 2> 1263141 INFO (coreCloseExecutor-4533-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 1263141 INFO (coreCloseExecutor-4533-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@39247eb2 commitCommandVersion:0
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_988AEB31DED7DE72-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=PostingsFormat(name=LuceneFixedGap), id=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1127, maxMBSortInHeap=7.431610812057165, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@218dcb19), locale=is-IS, timezone=EST5EDT
[junit4] 2> NOTE: Linux 4.13.0-39-generic amd64/Oracle Corporation 11-ea (64-bit)/cpus=8,threads=1,free=347905120,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestCollectionAPI, TestDirectoryFactory, SegmentsInfoRequestHandlerTest, TestSystemIdResolver, TestSizeLimitedDistributedMap, HdfsDirectoryTest, NodeLostTriggerIntegrationTest, CollectionsAPIDistributedZkTest, SpellCheckCollatorWithCollapseTest, TestPerFieldSimilarityWithDefaultOverride, PathHierarchyTokenizerFactoryTest, DeleteInactiveReplicaTest, TestXmlQParser, NodeMutatorTest, TestSolrConfigHandler, FacetPivotSmallTest, BadComponentTest, TestJsonFacetRefinement, TestBM25SimilarityFactory, TestReversedWildcardFilterFactory, CustomHighlightComponentTest, SystemLogListenerTest, TestCursorMarkWithoutUniqueKey, TestCoreBackup, AutoAddReplicasIntegrationTest, SolrXmlInZkTest, TestFieldTypeCollectionResource, CoreSorterTest, TestInPlaceUpdatesDistrib, TestOverriddenPrefixQueryForCustomFieldType, TestNumericTerms64, BigEndianAscendingWordDeserializerTest, TestReplicationHandler, HttpTriggerListenerTest, PKIAuthenticationIntegrationTest, TestCharFilters, TestLRUCache, TestCloudDeleteByQuery, TestInfoStreamLogging, UpdateRequestProcessorFactoryTest, ZkFailoverTest, TestSolrIndexConfig, SuggesterTest, PolyFieldTest, TestFieldCacheSort, SolrJmxReporterCloudTest, BlockJoinFacetSimpleTest, TestLRUStatsCache, SuggestComponentTest, TestInitQParser, TestSlowCompositeReaderWrapper, MergeStrategyTest, TestReqParamsAPI, FastVectorHighlighterTest, BJQParserTest, TestQueryUtils, SampleTest, HdfsChaosMonkeySafeLeaderTest, JavabinLoaderTest, TestImpersonationWithHadoopAuth, TriggerIntegrationTest, DocumentBuilderTest, TemplateUpdateProcessorTest, QueryParsingTest, SortByFunctionTest, HdfsBasicDistributedZk2Test, TestSurroundQueryParser, MetricTriggerTest, TestMacros, IndexSchemaTest, TestBinaryResponseWriter, HdfsRecoveryZkTest, ScheduledTriggerTest, TestTrie, AssignBackwardCompatibilityTest, TestFoldingMultitermQuery, HttpSolrCallGetCoreTest, CoreAdminRequestStatusTest, MoveReplicaTest, TestSolrCloudWithKerberosAlt, TestDeleteCollectionOnDownNodes, ClassificationUpdateProcessorIntegrationTest, TestElisionMultitermQuery, DirectUpdateHandlerOptimizeTest, TestCloudManagedSchema, SchemaVersionSpecificBehaviorTest, AtomicUpdateProcessorFactoryTest, TestGeoJSONResponseWriter, DistributedIntervalFacetingTest, TestSearchPerf, TestRTGBase, TestQueryWrapperFilter, HdfsDirectoryFactoryTest, CollectionReloadTest, RuleEngineTest, SolrCoreMetricManagerTest, ChaosMonkeySafeLeaderTest, TestFuzzyAnalyzedSuggestions, TestManagedSynonymFilterFactory, TestSSLRandomization, NumberUtilsTest, TestManagedResource, CheckHdfsIndexTest, TestSweetSpotSimilarityFactory, TestSolr4Spatial, TestPerFieldSimilarity, TestUseDocValuesAsStored2, ManagedSchemaRoundRobinCloudTest, TestRuleBasedAuthorizationPlugin, TestStressRecovery, SolrCoreTest, DocExpirationUpdateProcessorFactoryTest, TestTriggerIntegration, TestStandardQParsers, SoftAutoCommitTest, SubstringBytesRefFilterTest, CSVRequestHandlerTest, OverseerTest, RollingRestartTest, TestJsonRequest, BasicZkTest, ZkControllerTest, TestPushWriter, BooleanFieldTest, MaxSizeAutoCommitTest]
[junit4] Completed [376/806 (1!)] on J1 in 57.45s, 8 tests, 1 failure <<< FAILURES!
[...truncated 45306 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21979/consoleText
[repro] Revision: c3d28a5b0fbd56859d65a9907ad2b3064511b7e6
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=988AEB31DED7DE72 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=is-IS -Dtests.timezone=EST5EDT -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 3303 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.MaxSizeAutoCommitTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=988AEB31DED7DE72 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=is-IS -Dtests.timezone=EST5EDT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 111 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.MaxSizeAutoCommitTest
[repro] Exiting with code 0
[...truncated 40 lines...]