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/25 16:48:29 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-10.0.1) - Build #
22102 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22102/
Java: 64bit/jdk-10.0.1 -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_4C7CA07B3C9FB254-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_4C7CA07B3C9FB254-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
at __randomizedtesting.SeedInfo.seed([4C7CA07B3C9FB254:5C32458447318BA5]: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:844)
Build Log:
[...truncated 12566 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_4C7CA07B3C9FB254-001/init-core-data-001
[junit4] 2> 118682 INFO (SUITE-MaxSizeAutoCommitTest-seed#[4C7CA07B3C9FB254]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 118683 INFO (SUITE-MaxSizeAutoCommitTest-seed#[4C7CA07B3C9FB254]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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> 118683 INFO (SUITE-MaxSizeAutoCommitTest-seed#[4C7CA07B3C9FB254]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 118684 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteTest
[junit4] 2> 119011 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 119012 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] 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> 119088 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 119137 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 119348 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 119529 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 119559 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 119559 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 119569 INFO (coreLoadExecutor-396-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 119569 INFO (coreLoadExecutor-396-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> 119606 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 119620 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 119772 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 119795 INFO (coreLoadExecutor-396-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> 119796 INFO (coreLoadExecutor-396-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@2799f001
[junit4] 2> 119801 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 119801 INFO (coreLoadExecutor-396-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_4C7CA07B3C9FB254-001/init-core-data-001/]
[junit4] 2> 119803 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 119805 WARN (coreLoadExecutor-396-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> 119868 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 119868 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 119869 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 119869 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 119870 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=95.505859375, floorSegmentMB=1.1455078125, forceMergeDeletesPctAllowed=26.920283778454852, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 119871 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@79b7b472[collection1] main]
[junit4] 2> 119871 WARN (coreLoadExecutor-396-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> 119872 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 119872 INFO (coreLoadExecutor-396-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1601452396925419520
[junit4] 2> 119874 INFO (searcherExecutor-397-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@79b7b472[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 119875 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 119908 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 119909 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb72d5c commitCommandVersion:0
[junit4] 2> 120009 INFO (commitScheduler-400-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3dd520c1[collection1] realtime]
[junit4] 2> 120009 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 120009 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 120009 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb72d5c commitCommandVersion:0
[junit4] 2> 120135 INFO (commitScheduler-400-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a5192a[collection1] realtime]
[junit4] 2> 120136 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 120136 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 120136 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb72d5c commitCommandVersion:0
[junit4] 2> 120149 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1601452396931710976), 1 (1601452396933808128), 2 (1601452396934856704), 3 (1601452396935905280), 4 (1601452396935905281), 5 (1601452396941148160), 6 (1601452396942196736), 7 (1601452396943245312), 8 (1601452396944293888), 9 (1601452396945342464), ... (150 adds)]} 0 273
[junit4] 2> 120179 INFO (commitScheduler-400-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@953c35d[collection1] realtime]
[junit4] 2> 120179 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 120351 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[0 (-1601452397426638848), 1 (-1601452397427687424), 2 (-1601452397427687425), 3 (-1601452397427687426), 4 (-1601452397427687427), 5 (-1601452397427687428), 6 (-1601452397427687429), 7 (-1601452397427687430), 8 (-1601452397427687431), 9 (-1601452397427687432), ... (15 deletes)]} 0 1
[junit4] 2> 120564 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[15 (-1601452397649985536), 16 (-1601452397649985537), 17 (-1601452397649985538), 18 (-1601452397649985539), 19 (-1601452397649985540), 20 (-1601452397649985541), 21 (-1601452397649985542), 22 (-1601452397649985543), 23 (-1601452397649985544), 24 (-1601452397651034112), ... (15 deletes)]} 0 1
[junit4] 2> 120767 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[30 (-1601452397860749312), 31 (-1601452397861797888), 32 (-1601452397862846464), 33 (-1601452397862846465), 34 (-1601452397862846466), 35 (-1601452397862846467), 36 (-1601452397862846468), 37 (-1601452397862846469), 38 (-1601452397863895040), 39 (-1601452397863895041), ... (15 deletes)]} 0 2
[junit4] 2> 120972 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[45 (-1601452398077804544), 46 (-1601452398077804545), 47 (-1601452398077804546), 48 (-1601452398077804547), 49 (-1601452398077804548), 50 (-1601452398077804549), 51 (-1601452398077804550), 52 (-1601452398077804551), 53 (-1601452398077804552), 54 (-1601452398078853120), ... (15 deletes)]} 0 1
[junit4] 2> 120972 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 120972 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb72d5c commitCommandVersion:0
[junit4] 2> 120986 INFO (commitScheduler-400-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@71ee5c3d[collection1] realtime]
[junit4] 2> 120993 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 121024 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[60 (-1601452398131281920), 61 (-1601452398132330496), 62 (-1601452398132330497), 63 (-1601452398132330498), 64 (-1601452398132330499), 65 (-1601452398132330500), 66 (-1601452398132330501), 67 (-1601452398132330502), 68 (-1601452398132330503), 69 (-1601452398132330504), ... (15 deletes)]} 0 1
[junit4] 2> 121225 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[75 (-1601452398344142848), 76 (-1601452398344142849), 77 (-1601452398344142850), 78 (-1601452398344142851), 79 (-1601452398344142852), 80 (-1601452398344142853), 81 (-1601452398344142854), 82 (-1601452398344142855), 83 (-1601452398344142856), 84 (-1601452398344142857), ... (15 deletes)]} 0 1
[junit4] 2> 121427 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[90 (-1601452398554906624), 91 (-1601452398554906625), 92 (-1601452398554906626), 93 (-1601452398554906627), 94 (-1601452398554906628), 95 (-1601452398554906629), 96 (-1601452398555955200), 97 (-1601452398555955201), 98 (-1601452398555955202), 99 (-1601452398555955203), ... (15 deletes)]} 0 1
[junit4] 2> 121629 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[105 (-1601452398766718976), 106 (-1601452398766718977), 107 (-1601452398766718978), 108 (-1601452398766718979), 109 (-1601452398766718980), 110 (-1601452398766718981), 111 (-1601452398766718982), 112 (-1601452398766718983), 113 (-1601452398766718984), 114 (-1601452398766718985), ... (15 deletes)]} 0 1
[junit4] 2> 121630 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 121630 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb72d5c commitCommandVersion:0
[junit4] 2> 121646 INFO (commitScheduler-400-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@51032aa3[collection1] realtime]
[junit4] 2> 121647 INFO (commitScheduler-400-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 121680 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[120 (-1601452398820196352), 121 (-1601452398820196353), 122 (-1601452398821244928), 123 (-1601452398821244929), 124 (-1601452398821244930), 125 (-1601452398821244931), 126 (-1601452398821244932), 127 (-1601452398821244933), 128 (-1601452398821244934), 129 (-1601452398821244935), ... (15 deletes)]} 0 1
[junit4] 2> 121882 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[135 (-1601452399032008704), 136 (-1601452399032008705), 137 (-1601452399032008706), 138 (-1601452399032008707), 139 (-1601452399032008708), 140 (-1601452399032008709), 141 (-1601452399032008710), 142 (-1601452399032008711), 143 (-1601452399032008712), 144 (-1601452399032008713), ... (15 deletes)]} 0 1
[junit4] 2> 122083 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteTest
[junit4] 2> 122083 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 122083 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1184197301
[junit4] 2> 122083 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 122083 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c0043e5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4c154c87
[junit4] 2> 122088 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 122088 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e9b1255: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7e0877b4
[junit4] 2> 122091 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 122091 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1766dca7: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@d8c25d3
[junit4] 2> 122092 INFO (coreCloseExecutor-402-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@36e954
[junit4] 2> 122092 INFO (coreCloseExecutor-402-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=36e954
[junit4] 2> 122093 INFO (coreCloseExecutor-402-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a85be47: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2dedfe7f
[junit4] 2> 122111 INFO (coreCloseExecutor-402-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 122111 INFO (coreCloseExecutor-402-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb72d5c commitCommandVersion:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=4C7CA07B3C9FB254 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-BA -Dtests.timezone=Asia/Macau -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 3.47s J1 | MaxSizeAutoCommitTest.deleteTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_4C7CA07B3C9FB254-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
[junit4] > at __randomizedtesting.SeedInfo.seed([4C7CA07B3C9FB254:5C32458447318BA5]: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:844)
[junit4] 2> 122161 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###Starting endToEndTest {seed=[4C7CA07B3C9FB254:562ACF4DDC2D9556]}
[junit4] 2> 122161 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 122162 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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> 122194 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 122210 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 122407 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 122484 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 122493 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 122493 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 122501 INFO (coreLoadExecutor-409-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 122501 INFO (coreLoadExecutor-409-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> 122547 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 122586 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 122772 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 122806 INFO (coreLoadExecutor-409-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> 122806 INFO (coreLoadExecutor-409-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@2799f001
[junit4] 2> 122806 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 122806 INFO (coreLoadExecutor-409-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_4C7CA07B3C9FB254-001/init-core-data-001/]
[junit4] 2> 122809 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 122830 WARN (coreLoadExecutor-409-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> 122891 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 122891 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 122892 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 122892 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 122893 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=95.505859375, floorSegmentMB=1.1455078125, forceMergeDeletesPctAllowed=26.920283778454852, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 122894 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c487abe[collection1] main]
[junit4] 2> 122894 WARN (coreLoadExecutor-409-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> 122895 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 122895 INFO (coreLoadExecutor-409-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1601452400095264768
[junit4] 2> 122898 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 122930 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1601452400099459072), 1 (1601452400112041984), 2 (1601452400113090560), 3 (1601452400113090561), 4 (1601452400113090562), 5 (1601452400115187712), 6 (1601452400116236288), 7 (1601452400116236289), 8 (1601452400118333440), 9 (1601452400118333441), ... (20 adds)]} 0 30
[junit4] 2> 122937 INFO (searcherExecutor-410-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2c487abe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 123131 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=231
[junit4] 2> 123214 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1601452400422420480), 21 (1601452400423469056), 22 (1601452400423469057), 23 (1601452400424517632), 24 (1601452400424517633), 25 (1601452400424517634), 26 (1601452400425566208), 27 (1601452400425566209), 28 (1601452400425566210), 29 (1601452400425566211), ... (20 adds)]} 0 6
[junit4] 2> 123415 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=515
[junit4] 2> 123422 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1601452400640524288), 41 (1601452400641572864), 42 (1601452400641572865), 43 (1601452400641572866), 44 (1601452400641572867), 45 (1601452400641572868), 46 (1601452400645767168), 47 (1601452400646815744), 48 (1601452400646815745), 49 (1601452400646815746), ... (20 adds)]} 0 7
[junit4] 2> 123629 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=729
[junit4] 2> 123632 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1601452400864919552), 61 (1601452400864919553), 62 (1601452400865968128), 63 (1601452400865968129), 64 (1601452400865968130), 65 (1601452400865968131), 66 (1601452400865968132), 67 (1601452400865968133), 68 (1601452400865968134), 69 (1601452400865968135), ... (20 adds)]} 0 2
[junit4] 2> 123835 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=935
[junit4] 2> 123838 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1601452401080926208), 81 (1601452401081974784), 82 (1601452401081974785), 83 (1601452401081974786), 84 (1601452401081974787), 85 (1601452401081974788), 86 (1601452401083023360), 87 (1601452401083023361), 88 (1601452401083023362), 89 (1601452401083023363), ... (20 adds)]} 0 3
[junit4] 2> 124039 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1140
[junit4] 2> 124042 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1601452401295884288), 101 (1601452401295884289), 102 (1601452401295884290), 103 (1601452401295884291), 104 (1601452401295884292), 105 (1601452401296932864), 106 (1601452401296932865), 107 (1601452401296932866), 108 (1601452401296932867), 109 (1601452401296932868), ... (20 adds)]} 0 2
[junit4] 2> 124243 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1343
[junit4] 2> 124263 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1601452401509793792), 121 (1601452401509793793), 122 (1601452401509793794), 123 (1601452401509793795), 124 (1601452401510842368), 125 (1601452401510842369), 126 (1601452401510842370), 127 (1601452401510842371), 128 (1601452401510842372), 129 (1601452401510842373), ... (20 adds)]} 0 19
[junit4] 2> 124265 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 124265 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 124313 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1413
[junit4] 2> 124317 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1601452401582145536), 141 (1601452401584242688), 142 (1601452401584242689), 143 (1601452401584242690), 144 (1601452401584242691), 145 (1601452401585291264), 146 (1601452401585291265), 147 (1601452401585291266), 148 (1601452401585291267), 149 (1601452401585291268), ... (20 adds)]} 0 3
[junit4] 2> 124332 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f506277[collection1] realtime]
[junit4] 2> 124332 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 124521 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1621
[junit4] 2> 124524 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1601452401800249344), 161 (1601452401801297920), 162 (1601452401801297921), 163 (1601452401801297922), 164 (1601452401802346496), 165 (1601452401802346497), 166 (1601452401802346498), 167 (1601452401802346499), 168 (1601452401802346500), 169 (1601452401802346501), ... (20 adds)]} 0 2
[junit4] 2> 124727 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1828
[junit4] 2> 124730 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1601452402017304576), 181 (1601452402017304577), 182 (1601452402017304578), 183 (1601452402017304579), 184 (1601452402017304580), 185 (1601452402017304581), 186 (1601452402018353152), 187 (1601452402018353153), 188 (1601452402018353154), 189 (1601452402018353155), ... (20 adds)]} 0 2
[junit4] 2> 124931 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=2031
[junit4] 2> 124935 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 (1601452402230165504), 201 (1601452402231214080), 202 (1601452402231214081), 203 (1601452402231214082), 204 (1601452402231214083), 205 (1601452402231214084), 206 (1601452402231214085), 207 (1601452402233311232), 208 (1601452402233311233), 209 (1601452402233311234), ... (20 adds)]} 0 3
[junit4] 2> 125136 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=2236
[junit4] 2> 125147 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 (1601452402446172160), 221 (1601452402449317888), 222 (1601452402449317889), 223 (1601452402449317890), 224 (1601452402450366464), 225 (1601452402450366465), 226 (1601452402450366466), 227 (1601452402450366467), 228 (1601452402450366468), 229 (1601452402450366469), ... (20 adds)]} 0 10
[junit4] 2> 125347 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=2448
[junit4] 2> 125352 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 (1601452402667421696), 241 (1601452402667421697), 242 (1601452402670567424), 243 (1601452402670567425), 244 (1601452402670567426), 245 (1601452402670567427), 246 (1601452402670567428), 247 (1601452402670567429), 248 (1601452402670567430), 249 (1601452402671616000), ... (20 adds)]} 0 4
[junit4] 2> 125556 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=2657
[junit4] 2> 125559 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 (1601452402886574080), 261 (1601452402886574081), 262 (1601452402886574082), 263 (1601452402886574083), 264 (1601452402887622656), 265 (1601452402887622657), 266 (1601452402887622658), 267 (1601452402887622659), 268 (1601452402887622660), 269 (1601452402887622661), ... (20 adds)]} 0 2
[junit4] 2> 125563 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 125563 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 125580 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@51dde84e[collection1] realtime]
[junit4] 2> 125581 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 125610 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=2710
[junit4] 2> 125613 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 (1601452402942148608), 281 (1601452402943197184), 282 (1601452402943197185), 283 (1601452402944245760), 284 (1601452402944245761), 285 (1601452402944245762), 286 (1601452402944245763), 287 (1601452402944245764), 288 (1601452402944245765), 289 (1601452402944245766), ... (20 adds)]} 0 2
[junit4] 2> 125817 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=2917
[junit4] 2> 125818 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 (1601452403159203840), 301 (1601452403159203841), 302 (1601452403159203842), 303 (1601452403160252416), 304 (1601452403160252417), 305 (1601452403160252418), 306 (1601452403160252419), 307 (1601452403160252420), 308 (1601452403160252421), 309 (1601452403160252422), ... (20 adds)]} 0 1
[junit4] 2> 126023 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=3123
[junit4] 2> 126025 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 (1601452403375210496), 321 (1601452403376259072), 322 (1601452403376259073), 323 (1601452403376259074), 324 (1601452403376259075), 325 (1601452403376259076), 326 (1601452403376259077), 327 (1601452403376259078), 328 (1601452403376259079), 329 (1601452403376259080), ... (20 adds)]} 0 1
[junit4] 2> 126237 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=3337
[junit4] 2> 126239 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 (1601452403599605760), 341 (1601452403599605761), 342 (1601452403599605762), 343 (1601452403599605763), 344 (1601452403600654336), 345 (1601452403600654337), 346 (1601452403600654338), 347 (1601452403600654339), 348 (1601452403600654340), 349 (1601452403600654341), ... (20 adds)]} 0 1
[junit4] 2> 126443 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=3543
[junit4] 2> 126445 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 (1601452403815612416), 361 (1601452403815612417), 362 (1601452403816660992), 363 (1601452403816660993), 364 (1601452403816660994), 365 (1601452403816660995), 366 (1601452403816660996), 367 (1601452403816660997), 368 (1601452403816660998), 369 (1601452403816660999), ... (20 adds)]} 0 1
[junit4] 2> 126646 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=3746
[junit4] 2> 126647 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 (1601452404028473344), 381 (1601452404028473345), 382 (1601452404028473346), 383 (1601452404028473347), 384 (1601452404028473348), 385 (1601452404028473349), 386 (1601452404028473350), 387 (1601452404029521920), 388 (1601452404029521921), 389 (1601452404029521922), ... (20 adds)]} 0 1
[junit4] 2> 126849 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=3949
[junit4] 2> 126851 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 (1601452404241334272), 401 (1601452404241334273), 402 (1601452404241334274), 403 (1601452404242382848), 404 (1601452404242382849), 405 (1601452404242382850), 406 (1601452404242382851), 407 (1601452404242382852), 408 (1601452404242382853), 409 (1601452404243431424), ... (20 adds)]} 0 2
[junit4] 2> 126852 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126852 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 126879 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4edb1bfc[collection1] realtime]
[junit4] 2> 126879 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 126902 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=4002
[junit4] 2> 126905 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 (1601452404296908800), 421 (1601452404299005952), 422 (1601452404299005953), 423 (1601452404299005954), 424 (1601452404299005955), 425 (1601452404299005956), 426 (1601452404299005957), 427 (1601452404299005958), 428 (1601452404299005959), 429 (1601452404299005960), ... (20 adds)]} 0 2
[junit4] 2> 127113 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=4214
[junit4] 2> 127115 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 (1601452404519206912), 441 (1601452404519206913), 442 (1601452404519206914), 443 (1601452404519206915), 444 (1601452404519206916), 445 (1601452404519206917), 446 (1601452404519206918), 447 (1601452404519206919), 448 (1601452404519206920), 449 (1601452404520255488), ... (20 adds)]} 0 1
[junit4] 2> 127317 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=4417
[junit4] 2> 127319 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 (1601452404732067840), 461 (1601452404733116416), 462 (1601452404733116417), 463 (1601452404733116418), 464 (1601452404733116419), 465 (1601452404733116420), 466 (1601452404733116421), 467 (1601452404733116422), 468 (1601452404733116423), 469 (1601452404733116424), ... (20 adds)]} 0 1
[junit4] 2> 127520 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=4620
[junit4] 2> 127529 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 (1601452404944928768), 481 (1601452404944928769), 482 (1601452404945977344), 483 (1601452404945977345), 484 (1601452404945977346), 485 (1601452404945977347), 486 (1601452404945977348), 487 (1601452404945977349), 488 (1601452404945977350), 489 (1601452404945977351), ... (20 adds)]} 0 8
[junit4] 2> 127730 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=4830
[junit4] 2> 127732 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1601452405165129728), 501 (1601452405166178304), 502 (1601452405166178305), 503 (1601452405166178306), 504 (1601452405166178307), 505 (1601452405166178308), 506 (1601452405166178309), 507 (1601452405166178310), 508 (1601452405166178311), 509 (1601452405166178312), ... (20 adds)]} 0 1
[junit4] 2> 127937 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=5037
[junit4] 2> 127938 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 (1601452405382184960), 521 (1601452405382184961), 522 (1601452405382184962), 523 (1601452405382184963), 524 (1601452405383233536), 525 (1601452405383233537), 526 (1601452405383233538), 527 (1601452405383233539), 528 (1601452405383233540), 529 (1601452405383233541), ... (20 adds)]} 0 1
[junit4] 2> 128139 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=5240
[junit4] 2> 128142 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 (1601452405595045888), 541 (1601452405595045889), 542 (1601452405595045890), 543 (1601452405595045891), 544 (1601452405595045892), 545 (1601452405595045893), 546 (1601452405595045894), 547 (1601452405595045895), 548 (1601452405596094464), 549 (1601452405596094465), ... (20 adds)]} 0 2
[junit4] 2> 128144 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 128144 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 128176 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b5ec897[collection1] realtime]
[junit4] 2> 128176 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 128192 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=5292
[junit4] 2> 128195 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 (1601452405650620416), 561 (1601452405651668992), 562 (1601452405651668993), 563 (1601452405651668994), 564 (1601452405651668995), 565 (1601452405651668996), 566 (1601452405651668997), 567 (1601452405651668998), 568 (1601452405651668999), 569 (1601452405651669000), ... (20 adds)]} 0 2
[junit4] 2> 128397 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=5497
[junit4] 2> 128399 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 (1601452405864529920), 581 (1601452405865578496), 582 (1601452405865578497), 583 (1601452405865578498), 584 (1601452405865578499), 585 (1601452405865578500), 586 (1601452405865578501), 587 (1601452405865578502), 588 (1601452405865578503), 589 (1601452405865578504), ... (20 adds)]} 0 1
[junit4] 2> 128600 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=5700
[junit4] 2> 128602 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 (1601452406077390848), 601 (1601452406078439424), 602 (1601452406078439425), 603 (1601452406078439426), 604 (1601452406078439427), 605 (1601452406078439428), 606 (1601452406078439429), 607 (1601452406078439430), 608 (1601452406078439431), 609 (1601452406078439432), ... (20 adds)]} 0 1
[junit4] 2> 128807 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=5907
[junit4] 2> 128808 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 (1601452406294446080), 621 (1601452406294446081), 622 (1601452406294446082), 623 (1601452406294446083), 624 (1601452406294446084), 625 (1601452406294446085), 626 (1601452406294446086), 627 (1601452406294446087), 628 (1601452406294446088), 629 (1601452406294446089), ... (20 adds)]} 0 1
[junit4] 2> 129009 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=6109
[junit4] 2> 129010 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 (1601452406506258432), 641 (1601452406507307008), 642 (1601452406507307009), 643 (1601452406507307010), 644 (1601452406507307011), 645 (1601452406507307012), 646 (1601452406507307013), 647 (1601452406507307014), 648 (1601452406507307015), 649 (1601452406507307016), ... (20 adds)]} 0 1
[junit4] 2> 129211 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=6312
[junit4] 2> 129213 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 (1601452406719119360), 661 (1601452406719119361), 662 (1601452406719119362), 663 (1601452406719119363), 664 (1601452406719119364), 665 (1601452406719119365), 666 (1601452406719119366), 667 (1601452406719119367), 668 (1601452406719119368), 669 (1601452406719119369), ... (20 adds)]} 0 1
[junit4] 2> 129413 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=6514
[junit4] 2> 129415 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 (1601452406930931712), 681 (1601452406930931713), 682 (1601452406930931714), 683 (1601452406930931715), 684 (1601452406930931716), 685 (1601452406930931717), 686 (1601452406930931718), 687 (1601452406930931719), 688 (1601452406930931720), 689 (1601452406930931721), ... (20 adds)]} 0 1
[junit4] 2> 129416 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 129416 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 129423 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f0d1641[collection1] realtime]
[junit4] 2> 129423 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 129465 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=6566
[junit4] 2> 129467 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[700 (1601452406985457664), 701 (1601452406985457665), 702 (1601452406985457666), 703 (1601452406985457667), 704 (1601452406985457668), 705 (1601452406986506240), 706 (1601452406986506241), 707 (1601452406986506242), 708 (1601452406986506243), 709 (1601452406986506244), ... (20 adds)]} 0 1
[junit4] 2> 129668 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=6768
[junit4] 2> 129669 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[720 (1601452407197270016), 721 (1601452407197270017), 722 (1601452407197270018), 723 (1601452407197270019), 724 (1601452407197270020), 725 (1601452407198318592), 726 (1601452407198318593), 727 (1601452407198318594), 728 (1601452407198318595), 729 (1601452407198318596), ... (20 adds)]} 0 1
[junit4] 2> 129870 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=6970
[junit4] 2> 129871 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[740 (1601452407409082368), 741 (1601452407410130944), 742 (1601452407410130945), 743 (1601452407410130946), 744 (1601452407410130947), 745 (1601452407410130948), 746 (1601452407410130949), 747 (1601452407410130950), 748 (1601452407410130951), 749 (1601452407410130952), ... (20 adds)]} 0 1
[junit4] 2> 130072 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=7173
[junit4] 2> 130074 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[760 (1601452407621943296), 761 (1601452407621943297), 762 (1601452407621943298), 763 (1601452407621943299), 764 (1601452407621943300), 765 (1601452407621943301), 766 (1601452407621943302), 767 (1601452407621943303), 768 (1601452407621943304), 769 (1601452407621943305), ... (20 adds)]} 0 1
[junit4] 2> 130274 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=7375
[junit4] 2> 130276 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[780 (1601452407833755648), 781 (1601452407833755649), 782 (1601452407833755650), 783 (1601452407833755651), 784 (1601452407833755652), 785 (1601452407833755653), 786 (1601452407833755654), 787 (1601452407833755655), 788 (1601452407833755656), 789 (1601452407833755657), ... (20 adds)]} 0 1
[junit4] 2> 130477 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=7577
[junit4] 2> 130478 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[800 (1601452408045568000), 801 (1601452408045568001), 802 (1601452408045568002), 803 (1601452408045568003), 804 (1601452408045568004), 805 (1601452408045568005), 806 (1601452408045568006), 807 (1601452408045568007), 808 (1601452408045568008), 809 (1601452408046616576), ... (20 adds)]} 0 1
[junit4] 2> 130679 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=7779
[junit4] 2> 130690 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[820 (1601452408257380352), 821 (1601452408257380353), 822 (1601452408257380354), 823 (1601452408257380355), 824 (1601452408258428928), 825 (1601452408258428929), 826 (1601452408258428930), 827 (1601452408258428931), 828 (1601452408267866112), 829 (1601452408267866113), ... (20 adds)]} 0 10
[junit4] 2> 130690 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 130690 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 130698 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b4e8252[collection1] realtime]
[junit4] 2> 130698 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 130740 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=7840
[junit4] 2> 130742 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[840 (1601452408321343488), 841 (1601452408322392064), 842 (1601452408322392065), 843 (1601452408323440640), 844 (1601452408323440641), 845 (1601452408323440642), 846 (1601452408323440643), 847 (1601452408323440644), 848 (1601452408323440645), 849 (1601452408323440646), ... (20 adds)]} 0 1
[junit4] 2> 130944 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=8045
[junit4] 2> 130948 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[860 (1601452408537350144), 861 (1601452408538398720), 862 (1601452408538398721), 863 (1601452408538398722), 864 (1601452408538398723), 865 (1601452408538398724), 866 (1601452408538398725), 867 (1601452408538398726), 868 (1601452408538398727), 869 (1601452408538398728), ... (20 adds)]} 0 1
[junit4] 2> 131149 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=8249
[junit4] 2> 131150 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[880 (1601452408750211072), 881 (1601452408750211073), 882 (1601452408750211074), 883 (1601452408750211075), 884 (1601452408750211076), 885 (1601452408750211077), 886 (1601452408750211078), 887 (1601452408750211079), 888 (1601452408750211080), 889 (1601452408751259648), ... (20 adds)]} 0 1
[junit4] 2> 131351 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=8451
[junit4] 2> 131352 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[900 (1601452408962023424), 901 (1601452408962023425), 902 (1601452408962023426), 903 (1601452408962023427), 904 (1601452408962023428), 905 (1601452408962023429), 906 (1601452408962023430), 907 (1601452408963072000), 908 (1601452408963072001), 909 (1601452408963072002), ... (20 adds)]} 0 0
[junit4] 2> 131553 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=8653
[junit4] 2> 131554 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[920 (1601452409173835776), 921 (1601452409173835777), 922 (1601452409173835778), 923 (1601452409173835779), 924 (1601452409173835780), 925 (1601452409173835781), 926 (1601452409173835782), 927 (1601452409173835783), 928 (1601452409173835784), 929 (1601452409174884352), ... (20 adds)]} 0 1
[junit4] 2> 131755 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=8855
[junit4] 2> 131756 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 (1601452409385648128), 941 (1601452409385648129), 942 (1601452409385648130), 943 (1601452409385648131), 944 (1601452409385648132), 945 (1601452409385648133), 946 (1601452409385648134), 947 (1601452409386696704), 948 (1601452409386696705), 949 (1601452409386696706), ... (20 adds)]} 0 0
[junit4] 2> 131957 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=9057
[junit4] 2> 131958 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 (1601452409597460480), 961 (1601452409597460481), 962 (1601452409597460482), 963 (1601452409597460483), 964 (1601452409597460484), 965 (1601452409597460485), 966 (1601452409597460486), 967 (1601452409597460487), 968 (1601452409598509056), 969 (1601452409598509057), ... (20 adds)]} 0 1
[junit4] 2> 131959 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 131959 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 131970 INFO (commitScheduler-413-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e1df48b[collection1] realtime]
[junit4] 2> 131971 INFO (commitScheduler-413-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 132009 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=9109
[junit4] 2> 132011 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 (1601452409651986432), 981 (1601452409653035008), 982 (1601452409653035009), 983 (1601452409653035010), 984 (1601452409653035011), 985 (1601452409653035012), 986 (1601452409653035013), 987 (1601452409653035014), 988 (1601452409654083584), 989 (1601452409654083585), ... (20 adds)]} 0 2
[junit4] 2> 132212 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=9312
[junit4] 2> 132212 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 132212 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b37c485 commitCommandVersion:0
[junit4] 2> 132217 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40612b5f[collection1] main]
[junit4] 2> 132218 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 132218 INFO (searcherExecutor-410-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@40612b5f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C140) Uninverting(_1(8.0.0):C20) Uninverting(_2(8.0.0):C120) 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):C140) Uninverting(_8(8.0.0):C20)))}
[junit4] 2> 132269 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=9369
[junit4] 2> 132273 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###Ending endToEndTest {seed=[4C7CA07B3C9FB254:562ACF4DDC2D9556]}
[junit4] 2> 132273 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 132273 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1975024626
[junit4] 2> 132273 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 132273 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f86492e: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2c17f0c3
[junit4] 2> 132278 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 132278 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4749922d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7e0877b4
[junit4] 2> 132281 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 132281 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7317a22: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@d8c25d3
[junit4] 2> 132281 INFO (coreCloseExecutor-415-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@43362831
[junit4] 2> 132281 INFO (coreCloseExecutor-415-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=43362831
[junit4] 2> 132282 INFO (coreCloseExecutor-415-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f444eb0: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7ed1b6e1
[junit4] 2> 132305 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###Starting endToEndTest {seed=[4C7CA07B3C9FB254:E27C73B1E8EF5E7A]}
[junit4] 2> 132305 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 132306 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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> 132350 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 132357 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 132420 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 132507 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 132515 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 132516 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2799f001
[junit4] 2> 132517 INFO (coreLoadExecutor-422-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 132517 INFO (coreLoadExecutor-422-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> 132534 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 132545 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 132653 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 132664 INFO (coreLoadExecutor-422-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> 132664 INFO (coreLoadExecutor-422-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@2799f001
[junit4] 2> 132664 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 132664 INFO (coreLoadExecutor-422-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_4C7CA07B3C9FB254-001/init-core-data-001/]
[junit4] 2> 132666 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 132670 WARN (coreLoadExecutor-422-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> 132710 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 132710 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 132712 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 132712 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 132713 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=95.505859375, floorSegmentMB=1.1455078125, forceMergeDeletesPctAllowed=26.920283778454852, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 132713 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@35d81b21[collection1] main]
[junit4] 2> 132714 WARN (coreLoadExecutor-422-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> 132714 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 132714 INFO (coreLoadExecutor-422-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1601452410391232512
[junit4] 2> 132717 INFO (searcherExecutor-423-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@35d81b21[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 132717 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 132720 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1601452410395426816), 1 (1601452410397523968), 2 (1601452410397523969), 3 (1601452410397523970), 4 (1601452410397523971), 5 (1601452410397523972), 6 (1601452410397523973), 7 (1601452410397523974), 8 (1601452410397523975), 9 (1601452410397523976), ... (20 adds)]} 0 2
[junit4] 2> 132921 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=203
[junit4] 2> 132924 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1601452410610384896), 21 (1601452410610384897), 22 (1601452410610384898), 23 (1601452410610384899), 24 (1601452410610384900), 25 (1601452410610384901), 26 (1601452410610384902), 27 (1601452410610384903), 28 (1601452410610384904), 29 (1601452410610384905), ... (20 adds)]} 0 1
[junit4] 2> 133125 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=406
[junit4] 2> 133126 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1601452410822197248), 41 (1601452410822197249), 42 (1601452410822197250), 43 (1601452410822197251), 44 (1601452410822197252), 45 (1601452410822197253), 46 (1601452410822197254), 47 (1601452410822197255), 48 (1601452410823245824), 49 (1601452410823245825), ... (20 adds)]} 0 1
[junit4] 2> 133327 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=609
[junit4] 2> 133328 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1601452411034009600), 61 (1601452411034009601), 62 (1601452411034009602), 63 (1601452411035058176), 64 (1601452411035058177), 65 (1601452411035058178), 66 (1601452411035058179), 67 (1601452411035058180), 68 (1601452411035058181), 69 (1601452411035058182), ... (20 adds)]} 0 1
[junit4] 2> 133531 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=813
[junit4] 2> 133532 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1601452411247919104), 81 (1601452411247919105), 82 (1601452411247919106), 83 (1601452411247919107), 84 (1601452411247919108), 85 (1601452411248967680), 86 (1601452411248967681), 87 (1601452411248967682), 88 (1601452411248967683), 89 (1601452411248967684), ... (20 adds)]} 0 1
[junit4] 2> 133733 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1015
[junit4] 2> 133734 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1601452411459731456), 101 (1601452411460780032), 102 (1601452411460780033), 103 (1601452411460780034), 104 (1601452411460780035), 105 (1601452411460780036), 106 (1601452411460780037), 107 (1601452411460780038), 108 (1601452411460780039), 109 (1601452411460780040), ... (20 adds)]} 0 1
[junit4] 2> 133937 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1218
[junit4] 2> 133938 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1601452411673640960), 121 (1601452411673640961), 122 (1601452411673640962), 123 (1601452411673640963), 124 (1601452411673640964), 125 (1601452411673640965), 126 (1601452411673640966), 127 (1601452411673640967), 128 (1601452411674689536), 129 (1601452411674689537), ... (20 adds)]} 0 1
[junit4] 2> 133939 INFO (commitScheduler-426-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 133939 INFO (commitScheduler-426-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@579e08b commitCommandVersion:0
[junit4] 2> 133945 INFO (commitScheduler-426-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d1b7874[collection1] realtime]
[junit4] 2> 133946 INFO (commitScheduler-426-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 133989 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1271
[junit4] 2> 133991 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1601452411728166912), 141 (1601452411729215488), 142 (1601452411729215489), 143 (1601452411729215490), 144 (1601452411729215491), 145 (1601452411729215492), 146 (1601452411729215493), 147 (1601452411729215494), 148 (1601452411730264064), 149 (1601452411730264065), ... (20 adds)]} 0 1
[junit4] 2> 134192 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1473
[junit4] 2> 134193 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1601452411941027840), 161 (1601452411941027841), 162 (1601452411941027842), 163 (1601452411941027843), 164 (1601452411941027844), 165 (1601452411941027845), 166 (1601452411942076416), 167 (1601452411942076417), 168 (1601452411942076418), 169 (1601452411942076419), ... (20 adds)]} 0 1
[junit4] 2> 134394 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1676
[junit4] 2> 134395 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1601452412152840192), 181 (1601452412152840193), 182 (1601452412152840194), 183 (1601452412152840195), 184 (1601452412152840196), 185 (1601452412153888768), 186 (1601452412153888769), 187 (1601452412153888770), 188 (1601452412153888771), 189 (1601452412153888772), ... (20 adds)]} 0 1
[junit4] 2> 134596 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[4C7CA07B3C9FB254]) [ ] 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=1878
[junit4] 2> 134598 INFO (TEST-MaxSizeAutoCommitTest.endToEn
[...truncated too long message...]
.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> 176911 INFO (coreLoadExecutor-487-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 176912 INFO (searcherExecutor-488-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43f3ed3c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 176912 INFO (coreLoadExecutor-487-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1601452456736194560
[junit4] 2> 176915 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 176917 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1601452456740388864), 1 (1601452456741437440), 2 (1601452456741437441)]} 0 2
[junit4] 2> 177118 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1601452456952201216), 4 (1601452456952201217), 5 (1601452456952201218)]} 0 0
[junit4] 2> 177319 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1601452457162964992), 7 (1601452457162964993), 8 (1601452457162964994)]} 0 0
[junit4] 2> 177521 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1601452457373728768), 10 (1601452457373728769), 11 (1601452457374777344)]} 0 0
[junit4] 2> 177721 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1601452457584492544), 13 (1601452457584492545), 14 (1601452457584492546)]} 0 0
[junit4] 2> 177922 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1601452457795256320), 16 (1601452457795256321), 17 (1601452457795256322)]} 0 0
[junit4] 2> 178123 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[18 (1601452458006020096), 19 (1601452458006020097), 20 (1601452458006020098)]} 0 0
[junit4] 2> 178324 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[21 (1601452458216783872), 22 (1601452458216783873), 23 (1601452458216783874)]} 0 0
[junit4] 2> 178526 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[24 (1601452458427547648), 25 (1601452458427547649), 26 (1601452458427547650)]} 0 0
[junit4] 2> 178526 INFO (commitScheduler-491-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 178527 INFO (commitScheduler-491-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@339143fe commitCommandVersion:0
[junit4] 2> 178564 INFO (commitScheduler-491-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@60aa97d6[collection1] realtime]
[junit4] 2> 178564 INFO (commitScheduler-491-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 178577 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[27 (1601452458481025024), 28 (1601452458482073600), 29 (1601452458482073601)]} 0 0
[junit4] 2> 178778 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###Ending simpleTest
[junit4] 2> 178778 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 178778 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1732010593
[junit4] 2> 178778 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 178778 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@42a704d6: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@34ea54e7
[junit4] 2> 178782 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 178782 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ca3151f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7e0877b4
[junit4] 2> 178785 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 178785 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[4C7CA07B3C9FB254]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50f78e17: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@d8c25d3
[junit4] 2> 178796 INFO (coreCloseExecutor-493-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@71610e30
[junit4] 2> 178797 INFO (coreCloseExecutor-493-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=71610e30
[junit4] 2> 178797 INFO (coreCloseExecutor-493-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55a04270: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4b4b247e
[junit4] 2> 178843 INFO (coreCloseExecutor-493-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 178844 INFO (coreCloseExecutor-493-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@339143fe 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_4C7CA07B3C9FB254-001
[junit4] 2> May 25, 2018 4:01:18 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=PostingsFormat(name=Asserting)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=943, maxMBSortInHeap=7.036506418387999, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1cc3ff40), locale=sr-BA, timezone=Asia/Macau
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=8,threads=1,free=282693632,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [HdfsBasicDistributedZkTest, TestCloudPseudoReturnFields, SimplePostToolTest, SuggesterFSTTest, TestFunctionQuery, DocExpirationUpdateProcessorFactoryTest, SearchHandlerTest, DistribJoinFromCollectionTest, DirectSolrConnectionTest, TestCloudConsistency, ExplicitHLLTest, TestZkChroot, MaxSizeAutoCommitTest]
[junit4] Completed [59/809 (1!)] on J1 in 60.55s, 8 tests, 1 failure <<< FAILURES!
[...truncated 46397 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22102/consoleText
[repro] Revision: 18ad8d137afa8e2017f4121ddced4d630b1c86a1
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=4C7CA07B3C9FB254 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-BA -Dtests.timezone=Asia/Macau -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[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=4C7CA07B3C9FB254 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-BA -Dtests.timezone=Asia/Macau -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 111 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.MaxSizeAutoCommitTest
[repro] Exiting with code 0
[...truncated 40 lines...]