[JENKINS] Lucene-Solr-Tests-7.x - Build # 771 - Still Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/771/

1 tests failed.
FAILED:  org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest

Error Message:
Tlog size exceeds the max size bound. Tlog path: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-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/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/tlog/tlog.0000000000000000003,
 tlog size: 1302
        at 
__randomizedtesting.SeedInfo.seed([3E85A1170572129:13A6BFEE0BF918D8]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:382)
        at 
org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:202)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 15244 lines...]
   [junit4] Suite: org.apache.solr.update.MaxSizeAutoCommitTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001
   [junit4]   2> 4286490 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4286799 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4286910 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4287572 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4289480 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4289480 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4289610 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4289721 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4289721 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4289739 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4289976 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4290089 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4291160 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4291267 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4291267 INFO  (coreLoadExecutor-11537-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@3ce546f1
   [junit4]   2> 4291267 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4291267 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/]
   [junit4]   2> 4291268 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.9384765625, 
floorSegmentMB=0.908203125, forceMergeDeletesPctAllowed=1.2948243395438896, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.37129738572993487, deletesPctAllowed=20.68653750676652
   [junit4]   2> 4291283 WARN  (coreLoadExecutor-11537-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> 4291697 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4291697 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4291698 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4291698 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4291712 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=6.322265625, 
floorSegmentMB=1.580078125, forceMergeDeletesPctAllowed=14.992196955303736, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7210172291965198, deletesPctAllowed=43.15834683229663
   [junit4]   2> 4291712 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@13586f22[collection1] main]
   [junit4]   2> 4291713 WARN  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4291713 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4291714 INFO  
(searcherExecutor-11538-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13586f22[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4291714 INFO  (coreLoadExecutor-11537-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1608639257228869632
   [junit4]   2> 4291773 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4291773 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
   [junit4]   2> 4291795 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@210a520a[collection1] realtime]
   [junit4]   2> 4291795 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4291821 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4291821 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
   [junit4]   2> 4291824 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@74470c81[collection1] realtime]
   [junit4]   2> 4291824 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4291861 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4291861 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
   [junit4]   2> 4291896 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@f50cdcf[collection1] realtime]
   [junit4]   2> 4291915 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4291932 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 
(1608639257249841152), 1 (1608639257265569792), 2 (1608639257265569793), 3 
(1608639257266618368), 4 (1608639257266618369), 5 (1608639257266618370), 6 
(1608639257266618371), 7 (1608639257266618372), 8 (1608639257266618373), 9 
(1608639257266618374), ... (150 adds)]} 0 198
   [junit4]   2> 4292152 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[0 
(-1608639257688145920), 1 (-1608639257688145921), 2 (-1608639257688145922), 3 
(-1608639257688145923), 4 (-1608639257688145924), 5 (-1608639257688145925), 6 
(-1608639257688145926), 7 (-1608639257688145927), 8 (-1608639257688145928), 9 
(-1608639257688145929), ... (15 deletes)]} 0 1
   [junit4]   2> 4292370 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[15 
(-1608639257916735488), 16 (-1608639257916735489), 17 (-1608639257916735490), 
18 (-1608639257916735491), 19 (-1608639257916735492), 20 
(-1608639257916735493), 21 (-1608639257916735494), 22 (-1608639257916735495), 
23 (-1608639257916735496), 24 (-1608639257916735497), ... (15 deletes)]} 0 0
   [junit4]   2> 4292590 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[30 
(-1608639258146373632), 31 (-1608639258146373633), 32 (-1608639258147422208), 
33 (-1608639258147422209), 34 (-1608639258147422210), 35 
(-1608639258147422211), 36 (-1608639258147422212), 37 (-1608639258147422213), 
38 (-1608639258147422214), 39 (-1608639258147422215), ... (15 deletes)]} 0 0
   [junit4]   2> 4292826 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[45 
(-1608639258393837568), 46 (-1608639258394886144), 47 (-1608639258394886145), 
48 (-1608639258394886146), 49 (-1608639258394886147), 50 
(-1608639258394886148), 51 (-1608639258394886149), 52 (-1608639258394886150), 
53 (-1608639258394886151), 54 (-1608639258394886152), ... (15 deletes)]} 0 0
   [junit4]   2> 4292827 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4292827 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
   [junit4]   2> 4292879 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[60 
(-1608639258449412096), 61 (-1608639258450460672), 62 (-1608639258450460673), 
63 (-1608639258450460674), 64 (-1608639258450460675), 65 
(-1608639258450460676), 66 (-1608639258450460677), 67 (-1608639258450460678), 
68 (-1608639258450460679), 69 (-1608639258450460680), ... (15 deletes)]} 0 0
   [junit4]   2> 4292880 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5491d682[collection1] realtime]
   [junit4]   2> 4292880 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4293098 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[75 
(-1608639258680098816), 76 (-1608639258680098817), 77 (-1608639258680098818), 
78 (-1608639258680098819), 79 (-1608639258680098820), 80 
(-1608639258680098821), 81 (-1608639258680098822), 82 (-1608639258680098823), 
83 (-1608639258680098824), 84 (-1608639258680098825), ... (15 deletes)]} 0 0
   [junit4]   2> 4293318 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[90 
(-1608639258909736960), 91 (-1608639258909736961), 92 (-1608639258909736962), 
93 (-1608639258909736963), 94 (-1608639258909736964), 95 
(-1608639258909736965), 96 (-1608639258909736966), 97 (-1608639258910785536), 
98 (-1608639258910785537), 99 (-1608639258910785538), ... (15 deletes)]} 0 0
   [junit4]   2> 4293537 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[105 
(-1608639259140423680), 106 (-1608639259140423681), 107 (-1608639259140423682), 
108 (-1608639259140423683), 109 (-1608639259140423684), 110 
(-1608639259140423685), 111 (-1608639259140423686), 112 (-1608639259140423687), 
113 (-1608639259140423688), 114 (-1608639259140423689), ... (15 deletes)]} 0 0
   [junit4]   2> 4293557 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4293557 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
   [junit4]   2> 4293595 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[120 
(-1608639259200192512), 121 (-1608639259201241088), 122 (-1608639259201241089), 
123 (-1608639259201241090), 124 (-1608639259201241091), 125 
(-1608639259201241092), 126 (-1608639259201241093), 127 (-1608639259201241094), 
128 (-1608639259201241095), 129 (-1608639259201241096), ... (15 deletes)]} 0 1
   [junit4]   2> 4293668 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@da1e888[collection1] realtime]
   [junit4]   2> 4293668 INFO  (commitScheduler-11541-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4293814 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[135 
(-1608639259429830656), 136 (-1608639259429830657), 137 (-1608639259429830658), 
138 (-1608639259429830659), 139 (-1608639259430879232), 140 
(-1608639259430879233), 141 (-1608639259430879234), 142 (-1608639259430879235), 
143 (-1608639259430879236), 144 (-1608639259430879237), ... (15 deletes)]} 0 0
   [junit4]   2> 4294049 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=530956483
   [junit4]   2> 4294050 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4294050 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5fcd9de6: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@295e1f01
   [junit4]   2> 4294107 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4294107 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@aa08725: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3637d942
   [junit4]   2> 4294159 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4294159 INFO  
(TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ea7d0fd: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@66cb95cb
   [junit4]   2> 4294216 INFO  (coreCloseExecutor-11543-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1f83e13b
   [junit4]   2> 4294216 INFO  (coreCloseExecutor-11543-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1f83e13b
   [junit4]   2> 4294216 INFO  (coreCloseExecutor-11543-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4a8f61b3: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2f6bf785
   [junit4]   2> 4294255 INFO  (coreCloseExecutor-11543-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 4294255 INFO  (coreCloseExecutor-11543-thread-1) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest 
-Dtests.seed=3E85A1170572129 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=en-US -Dtests.timezone=Asia/Anadyr -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 10.4s J1 | MaxSizeAutoCommitTest.deleteTest <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Tlog size exceeds the 
max size bound. Tlog path: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/tlog/tlog.0000000000000000003,
 tlog size: 1302
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3E85A1170572129:13A6BFEE0BF918D8]:0)
   [junit4]    >        at 
org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:382)
   [junit4]    >        at 
org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:202)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 4294664 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4294992 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4295046 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4295731 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4296959 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4296960 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4297071 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4297107 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4297107 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4297119 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4297225 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4297282 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4297810 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4297833 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4297846 INFO  (coreLoadExecutor-11553-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@3ce546f1
   [junit4]   2> 4297846 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4297846 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/]
   [junit4]   2> 4297847 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.9384765625, 
floorSegmentMB=0.908203125, forceMergeDeletesPctAllowed=1.2948243395438896, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.37129738572993487, deletesPctAllowed=20.68653750676652
   [junit4]   2> 4297848 WARN  (coreLoadExecutor-11553-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> 4298049 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4298049 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4298050 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4298050 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4298051 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=6.322265625, 
floorSegmentMB=1.580078125, forceMergeDeletesPctAllowed=14.992196955303736, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7210172291965198, deletesPctAllowed=43.15834683229663
   [junit4]   2> 4298051 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4e8ed571[collection1] main]
   [junit4]   2> 4298051 WARN  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4298052 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4298052 INFO  (coreLoadExecutor-11553-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1608639263874744320
   [junit4]   2> 4298065 INFO  
(searcherExecutor-11554-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4e8ed571[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4298086 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 
(1608639263891521536), 1 (1608639263909347328), 2 (1608639263910395904)]} 0 17
   [junit4]   2> 4298350 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 
(1608639264166248448), 4 (1608639264166248449), 5 (1608639264166248450)]} 0 19
   [junit4]   2> 4298566 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 
(1608639264413712384), 7 (1608639264413712385), 8 (1608639264413712386)]} 0 0
   [junit4]   2> 4298785 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 
(1608639264643350528), 10 (1608639264643350529), 11 (1608639264643350530)]} 0 0
   [junit4]   2> 4298997 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 
(1608639264864600064), 13 (1608639264865648640), 14 (1608639264865648641)]} 0 0
   [junit4]   2> 4299215 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 
(1608639265094238208), 16 (1608639265094238209), 17 (1608639265094238210)]} 0 0
   [junit4]   2> 4299434 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[18 
(1608639265323876352), 19 (1608639265323876353), 20 (1608639265323876354)]} 0 0
   [junit4]   2> 4299646 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[21 
(1608639265546174464), 22 (1608639265546174465), 23 (1608639265546174466)]} 0 0
   [junit4]   2> 4299881 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[24 
(1608639265775812608), 25 (1608639265775812609), 26 (1608639265775812610)]} 0 16
   [junit4]   2> 4299881 INFO  (commitScheduler-11557-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4299881 INFO  (commitScheduler-11557-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3de07d5d commitCommandVersion:0
   [junit4]   2> 4299883 INFO  (commitScheduler-11557-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@19a2b254[collection1] realtime]
   [junit4]   2> 4299883 INFO  (commitScheduler-11557-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4299939 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[27 
(1608639265852358656), 28 (1608639265853407232), 29 (1608639265853407233)]} 0 1
   [junit4]   2> 4300158 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=438235900
   [junit4]   2> 4300158 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4300158 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4e6b4848: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@1e9a2cb5
   [junit4]   2> 4300193 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4300193 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5befb80e: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3637d942
   [junit4]   2> 4300236 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4300236 INFO  
(TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@67b856a3: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@66cb95cb
   [junit4]   2> 4300271 INFO  (coreCloseExecutor-11559-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6cbceae3
   [junit4]   2> 4300271 INFO  (coreCloseExecutor-11559-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=6cbceae3
   [junit4]   2> 4300271 INFO  (coreCloseExecutor-11559-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@65f26b54: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@68b5341d
   [junit4]   2> 4300289 INFO  (coreCloseExecutor-11559-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 4300290 INFO  (coreCloseExecutor-11559-thread-1) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3de07d5d commitCommandVersion:0
   [junit4]   2> 4300430 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4300709 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4300762 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4301626 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4302566 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4302566 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4302679 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4302749 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4302749 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4302778 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4302962 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4303054 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4304285 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4304380 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4304380 INFO  (coreLoadExecutor-11569-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@3ce546f1
   [junit4]   2> 4304380 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4304380 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/]
   [junit4]   2> 4304382 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.9384765625, 
floorSegmentMB=0.908203125, forceMergeDeletesPctAllowed=1.2948243395438896, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.37129738572993487, deletesPctAllowed=20.68653750676652
   [junit4]   2> 4304397 WARN  (coreLoadExecutor-11569-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> 4304773 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4304773 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4304774 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4304774 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4304775 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=6.322265625, 
floorSegmentMB=1.580078125, forceMergeDeletesPctAllowed=14.992196955303736, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7210172291965198, deletesPctAllowed=43.15834683229663
   [junit4]   2> 4304775 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2639592c[collection1] main]
   [junit4]   2> 4304775 WARN  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4304776 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4304776 INFO  (coreLoadExecutor-11569-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1608639270925369344
   [junit4]   2> 4304794 INFO  
(searcherExecutor-11570-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2639592c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4304819 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 
(1608639270962069504), 1 (1608639270969409536), 2 (1608639270970458112), 3 
(1608639270970458113), 4 (1608639270970458114), 5 (1608639270970458115), 6 
(1608639270970458116), 7 (1608639270970458117), 8 (1608639270970458118), 9 
(1608639270970458119), ... (20 adds)]} 0 8
   [junit4]   2> 4305033 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=222
   [junit4]   2> 4305475 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 
(1608639271657275392), 21 (1608639271657275393), 22 (1608639271657275394), 23 
(1608639271657275395), 24 (1608639271657275396), 25 (1608639271657275397), 26 
(1608639271658323968), 27 (1608639271658323969), 28 (1608639271658323970), 29 
(1608639271658323971), ... (20 adds)]} 0 1
   [junit4]   2> 4305688 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=877
   [junit4]   2> 4305689 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 
(1608639271882719232), 41 (1608639271882719233), 42 (1608639271882719234), 43 
(1608639271882719235), 44 (1608639271882719236), 45 (1608639271882719237), 46 
(1608639271882719238), 47 (1608639271882719239), 48 (1608639271882719240), 49 
(1608639271882719241), ... (20 adds)]} 0 1
   [junit4]   2> 4305904 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=1092
   [junit4]   2> 4305905 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 
(1608639272108163072), 61 (1608639272108163073), 62 (1608639272108163074), 63 
(1608639272108163075), 64 (1608639272108163076), 65 (1608639272108163077), 66 
(1608639272108163078), 67 (1608639272108163079), 68 (1608639272108163080), 69 
(1608639272108163081), ... (20 adds)]} 0 0
   [junit4]   2> 4306123 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=1312
   [junit4]   2> 4306124 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 
(1608639272337801216), 81 (1608639272338849792), 82 (1608639272338849793), 83 
(1608639272338849794), 84 (1608639272338849795), 85 (1608639272338849796), 86 
(1608639272338849797), 87 (1608639272338849798), 88 (1608639272338849799), 89 
(1608639272338849800), ... (20 adds)]} 0 0
   [junit4]   2> 4306334 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=1523
   [junit4]   2> 4306336 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 
(1608639272560099328), 101 (1608639272560099329), 102 (1608639272560099330), 
103 (1608639272560099331), 104 (1608639272560099332), 105 
(1608639272560099333), 106 (1608639272560099334), 107 (1608639272560099335), 
108 (1608639272560099336), 109 (1608639272560099337), ... (20 adds)]} 0 0
   [junit4]   2> 4306549 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=1738
   [junit4]   2> 4306551 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 
(1608639272785543168), 121 (1608639272785543169), 122 (1608639272785543170), 
123 (1608639272785543171), 124 (1608639272785543172), 125 
(1608639272785543173), 126 (1608639272785543174), 127 (1608639272785543175), 
128 (1608639272785543176), 129 (1608639272785543177), ... (20 adds)]} 0 1
   [junit4]   2> 4306552 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4306552 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4306568 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7d984c7[collection1] realtime]
   [junit4]   2> 4306568 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4306614 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=1803
   [junit4]   2> 4306616 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 
(1608639272853700608), 141 (1608639272853700609), 142 (1608639272853700610), 
143 (1608639272853700611), 144 (1608639272853700612), 145 
(1608639272853700613), 146 (1608639272853700614), 147 (1608639272854749184), 
148 (1608639272854749185), 149 (1608639272854749186), ... (20 adds)]} 0 1
   [junit4]   2> 4306817 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=2006
   [junit4]   2> 4306818 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 
(1608639273065512960), 161 (1608639273066561536), 162 (1608639273066561537), 
163 (1608639273066561538), 164 (1608639273066561539), 165 
(1608639273066561540), 166 (1608639273066561541), 167 (1608639273066561542), 
168 (1608639273066561543), 169 (1608639273066561544), ... (20 adds)]} 0 0
   [junit4]   2> 4307023 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=2212
   [junit4]   2> 4307025 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 
(1608639273282568192), 181 (1608639273282568193), 182 (1608639273282568194), 
183 (1608639273282568195), 184 (1608639273282568196), 185 
(1608639273282568197), 186 (1608639273282568198), 187 (1608639273282568199), 
188 (1608639273282568200), 189 (1608639273282568201), ... (20 adds)]} 0 0
   [junit4]   2> 4307242 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=2430
   [junit4]   2> 4307243 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 
(1608639273511157760), 201 (1608639273511157761), 202 (1608639273511157762), 
203 (1608639273511157763), 204 (1608639273511157764), 205 
(1608639273511157765), 206 (1608639273512206336), 207 (1608639273512206337), 
208 (1608639273512206338), 209 (1608639273512206339), ... (20 adds)]} 0 0
   [junit4]   2> 4307464 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=2653
   [junit4]   2> 4307465 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 
(1608639273744990208), 221 (1608639273744990209), 222 (1608639273744990210), 
223 (1608639273744990211), 224 (1608639273744990212), 225 
(1608639273744990213), 226 (1608639273744990214), 227 (1608639273744990215), 
228 (1608639273744990216), 229 (1608639273744990217), ... (20 adds)]} 0 0
   [junit4]   2> 4307677 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=2866
   [junit4]   2> 4307678 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 
(1608639273967288320), 241 (1608639273967288321), 242 (1608639273967288322), 
243 (1608639273968336896), 244 (1608639273968336897), 245 
(1608639273968336898), 246 (1608639273968336899), 247 (1608639273968336900), 
248 (1608639273968336901), 249 (1608639273968336902), ... (20 adds)]} 0 0
   [junit4]   2> 4307890 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=3078
   [junit4]   2> 4307952 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 
(1608639274190635008), 261 (1608639274190635009), 262 (1608639274190635010), 
263 (1608639274190635011), 264 (1608639274190635012), 265 
(1608639274190635013), 266 (1608639274190635014), 267 (1608639274190635015), 
268 (1608639274200072192), 269 (1608639274200072193), ... (20 adds)]} 0 62
   [junit4]   2> 4307960 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4307960 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4307970 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@9abf4ba[collection1] realtime]
   [junit4]   2> 4307971 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4308009 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=3197
   [junit4]   2> 4308010 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 
(1608639274315415552), 281 (1608639274316464128), 282 (1608639274316464129), 
283 (1608639274316464130), 284 (1608639274316464131), 285 
(1608639274316464132), 286 (1608639274316464133), 287 (1608639274316464134), 
288 (1608639274316464135), 289 (1608639274316464136), ... (20 adds)]} 0 1
   [junit4]   2> 4308220 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=3408
   [junit4]   2> 4308221 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 
(1608639274536665088), 301 (1608639274536665089), 302 (1608639274536665090), 
303 (1608639274536665091), 304 (1608639274536665092), 305 
(1608639274536665093), 306 (1608639274536665094), 307 (1608639274536665095), 
308 (1608639274536665096), 309 (1608639274536665097), ... (20 adds)]} 0 0
   [junit4]   2> 4308423 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=3612
   [junit4]   2> 4308424 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 
(1608639274749526016), 321 (1608639274749526017), 322 (1608639274750574592), 
323 (1608639274750574593), 324 (1608639274750574594), 325 
(1608639274750574595), 326 (1608639274750574596), 327 (1608639274750574597), 
328 (1608639274750574598), 329 (1608639274750574599), ... (20 adds)]} 0 0
   [junit4]   2> 4308650 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=3838
   [junit4]   2> 4308651 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 
(1608639274987552768), 341 (1608639274987552769), 342 (1608639274987552770), 
343 (1608639274987552771), 344 (1608639274987552772), 345 
(1608639274987552773), 346 (1608639274987552774), 347 (1608639274988601344), 
348 (1608639274988601345), 349 (1608639274988601346), ... (20 adds)]} 0 0
   [junit4]   2> 4308866 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=4055
   [junit4]   2> 4308867 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 
(1608639275215093760), 361 (1608639275215093761), 362 (1608639275215093762), 
363 (1608639275215093763), 364 (1608639275215093764), 365 
(1608639275215093765), 366 (1608639275215093766), 367 (1608639275215093767), 
368 (1608639275215093768), 369 (1608639275215093769), ... (20 adds)]} 0 0
   [junit4]   2> 4309072 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=4261
   [junit4]   2> 4309073 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 
(1608639275431100416), 381 (1608639275431100417), 382 (1608639275431100418), 
383 (1608639275431100419), 384 (1608639275431100420), 385 
(1608639275431100421), 386 (1608639275431100422), 387 (1608639275431100423), 
388 (1608639275431100424), 389 (1608639275431100425), ... (20 adds)]} 0 0
   [junit4]   2> 4309275 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=4464
   [junit4]   2> 4309277 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 
(1608639275643961344), 401 (1608639275643961345), 402 (1608639275643961346), 
403 (1608639275643961347), 404 (1608639275643961348), 405 
(1608639275643961349), 406 (1608639275643961350), 407 (1608639275643961351), 
408 (1608639275643961352), 409 (1608639275643961353), ... (20 adds)]} 0 1
   [junit4]   2> 4309278 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4309278 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4309281 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d70777f[collection1] realtime]
   [junit4]   2> 4309281 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4309327 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=4516
   [junit4]   2> 4309329 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 
(1608639275697438720), 421 (1608639275698487296), 422 (1608639275698487297), 
423 (1608639275698487298), 424 (1608639275698487299), 425 
(1608639275698487300), 426 (1608639275698487301), 427 (1608639275699535872), 
428 (1608639275699535873), 429 (1608639275699535874), ... (20 adds)]} 0 1
   [junit4]   2> 4309543 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=4732
   [junit4]   2> 4309544 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 
(1608639275924979712), 441 (1608639275924979713), 442 (1608639275924979714), 
443 (1608639275924979715), 444 (1608639275924979716), 445 
(1608639275924979717), 446 (1608639275924979718), 447 (1608639275924979719), 
448 (1608639275924979720), 449 (1608639275924979721), ... (20 adds)]} 0 0
   [junit4]   2> 4309756 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=4944
   [junit4]   2> 4309757 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 
(1608639276147277824), 461 (1608639276147277825), 462 (1608639276147277826), 
463 (1608639276147277827), 464 (1608639276148326400), 465 
(1608639276148326401), 466 (1608639276148326402), 467 (1608639276148326403), 
468 (1608639276148326404), 469 (1608639276148326405), ... (20 adds)]} 0 0
   [junit4]   2> 4309966 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=5154
   [junit4]   2> 4309967 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 
(1608639276367478784), 481 (1608639276367478785), 482 (1608639276367478786), 
483 (1608639276367478787), 484 (1608639276367478788), 485 
(1608639276367478789), 486 (1608639276367478790), 487 (1608639276367478791), 
488 (1608639276367478792), 489 (1608639276367478793), ... (20 adds)]} 0 0
   [junit4]   2> 4310174 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=5363
   [junit4]   2> 4310175 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1608639276585582592), 501 (1608639276585582593), 502 (1608639276586631168), 
503 (1608639276586631169), 504 (1608639276586631170), 505 
(1608639276586631171), 506 (1608639276586631172), 507 (1608639276586631173), 
508 (1608639276586631174), 509 (1608639276586631175), ... (20 adds)]} 0 0
   [junit4]   2> 4310376 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=5565
   [junit4]   2> 4310377 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 
(1608639276798443520), 521 (1608639276798443521), 522 (1608639276798443522), 
523 (1608639276798443523), 524 (1608639276798443524), 525 
(1608639276798443525), 526 (1608639276798443526), 527 (1608639276798443527), 
528 (1608639276798443528), 529 (1608639276798443529), ... (20 adds)]} 0 0
   [junit4]   2> 4310581 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=5770
   [junit4]   2> 4310583 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 
(1608639277013401600), 541 (1608639277013401601), 542 (1608639277013401602), 
543 (1608639277013401603), 544 (1608639277013401604), 545 
(1608639277013401605), 546 (1608639277013401606), 547 (1608639277013401607), 
548 (1608639277013401608), 549 (1608639277013401609), ... (20 adds)]} 0 1
   [junit4]   2> 4310590 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4310590 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4310593 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@8b9326f[collection1] realtime]
   [junit4]   2> 4310594 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4310633 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=5822
   [junit4]   2> 4310635 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 
(1608639277066878976), 561 (1608639277067927552), 562 (1608639277067927553), 
563 (1608639277067927554), 564 (1608639277067927555), 565 
(1608639277067927556), 566 (1608639277067927557), 567 (1608639277067927558), 
568 (1608639277067927559), 569 (1608639277067927560), ... (20 adds)]} 0 1
   [junit4]   2> 4310863 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=6051
   [junit4]   2> 4310864 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 
(1608639277308051456), 581 (1608639277308051457), 582 (1608639277308051458), 
583 (1608639277308051459), 584 (1608639277308051460), 585 
(1608639277308051461), 586 (1608639277308051462), 587 (1608639277308051463), 
588 (1608639277308051464), 589 (1608639277308051465), ... (20 adds)]} 0 0
   [junit4]   2> 4311065 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=6253
   [junit4]   2> 4311066 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 
(1608639277519863808), 601 (1608639277519863809), 602 (1608639277519863810), 
603 (1608639277519863811), 604 (1608639277519863812), 605 
(1608639277519863813), 606 (1608639277519863814), 607 (1608639277519863815), 
608 (1608639277519863816), 609 (1608639277519863817), ... (20 adds)]} 0 0
   [junit4]   2> 4311267 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=6455
   [junit4]   2> 4311268 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 
(1608639277731676160), 621 (1608639277731676161), 622 (1608639277731676162), 
623 (1608639277731676163), 624 (1608639277731676164), 625 
(1608639277731676165), 626 (1608639277731676166), 627 (1608639277731676167), 
628 (1608639277732724736), 629 (1608639277732724737), ... (20 adds)]} 0 0
   [junit4]   2> 4311469 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=6657
   [junit4]   2> 4311470 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 
(1608639277943488512), 641 (1608639277943488513), 642 (1608639277944537088), 
643 (1608639277944537089), 644 (1608639277944537090), 645 
(1608639277944537091), 646 (1608639277944537092), 647 (1608639277944537093), 
648 (1608639277944537094), 649 (1608639277944537095), ... (20 adds)]} 0 0
   [junit4]   2> 4311683 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=6872
   [junit4]   2> 4311684 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 
(1608639278167883776), 661 (1608639278168932352), 662 (1608639278168932353), 
663 (1608639278168932354), 664 (1608639278168932355), 665 
(1608639278168932356), 666 (1608639278168932357), 667 (1608639278168932358), 
668 (1608639278168932359), 669 (1608639278168932360), ... (20 adds)]} 0 0
   [junit4]   2> 4311900 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=7088
   [junit4]   2> 4311915 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 
(1608639278395424768), 681 (1608639278395424769), 682 (1608639278395424770), 
683 (1608639278395424771), 684 (1608639278395424772), 685 
(1608639278395424773), 686 (1608639278395424774), 687 (1608639278396473344), 
688 (1608639278410104832), 689 (1608639278410104833), ... (20 adds)]} 0 14
   [junit4]   2> 4311916 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4311916 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4311971 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=7160
   [junit4]   2> 4311973 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[700 
(1608639278470922240), 701 (1608639278471970816), 702 (1608639278471970817), 
703 (1608639278471970818), 704 (1608639278471970819), 705 
(1608639278471970820), 706 (1608639278471970821), 707 (1608639278471970822), 
708 (1608639278471970823), 709 (1608639278471970824), ... (20 adds)]} 0 1
   [junit4]   2> 4311974 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@24b15eed[collection1] realtime]
   [junit4]   2> 4311974 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4312207 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=7396
   [junit4]   2> 4312208 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[720 
(1608639278718386176), 721 (1608639278718386177), 722 (1608639278718386178), 
723 (1608639278718386179), 724 (1608639278718386180), 725 
(1608639278718386181), 726 (1608639278718386182), 727 (1608639278718386183), 
728 (1608639278718386184), 729 (1608639278718386185), ... (20 adds)]} 0 0
   [junit4]   2> 4312431 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=7620
   [junit4]   2> 4312432 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[740 
(1608639278952218624), 741 (1608639278952218625), 742 (1608639278952218626), 
743 (1608639278953267200), 744 (1608639278953267201), 745 
(1608639278953267202), 746 (1608639278953267203), 747 (1608639278953267204), 
748 (1608639278953267205), 749 (1608639278953267206), ... (20 adds)]} 0 0
   [junit4]   2> 4312650 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=7839
   [junit4]   2> 4312652 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[760 
(1608639279182905344), 761 (1608639279182905345), 762 (1608639279182905346), 
763 (1608639279182905347), 764 (1608639279182905348), 765 
(1608639279182905349), 766 (1608639279182905350), 767 (1608639279182905351), 
768 (1608639279182905352), 769 (1608639279182905353), ... (20 adds)]} 0 0
   [junit4]   2> 4312861 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=8050
   [junit4]   2> 4312863 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[780 
(1608639279404154880), 781 (1608639279404154881), 782 (1608639279404154882), 
783 (1608639279404154883), 784 (1608639279404154884), 785 
(1608639279404154885), 786 (1608639279404154886), 787 (1608639279404154887), 
788 (1608639279404154888), 789 (1608639279404154889), ... (20 adds)]} 0 0
   [junit4]   2> 4313066 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=8255
   [junit4]   2> 4313067 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[800 
(1608639279618064384), 801 (1608639279618064385), 802 (1608639279618064386), 
803 (1608639279619112960), 804 (1608639279619112961), 805 
(1608639279619112962), 806 (1608639279619112963), 807 (1608639279619112964), 
808 (1608639279619112965), 809 (1608639279619112966), ... (20 adds)]} 0 0
   [junit4]   2> 4313280 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=8469
   [junit4]   2> 4313283 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[820 
(1608639279842459648), 821 (1608639279843508224), 822 (1608639279843508225), 
823 (1608639279843508226), 824 (1608639279843508227), 825 
(1608639279843508228), 826 (1608639279843508229), 827 (1608639279843508230), 
828 (1608639279845605376), 829 (1608639279845605377), ... (20 adds)]} 0 2
   [junit4]   2> 4313284 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4313284 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4313288 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a926a02[collection1] realtime]
   [junit4]   2> 4313288 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4313334 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=8522
   [junit4]   2> 4313335 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[840 
(1608639279899082752), 841 (1608639279900131328), 842 (1608639279900131329), 
843 (1608639279900131330), 844 (1608639279900131331), 845 
(1608639279900131332), 846 (1608639279900131333), 847 (1608639279900131334), 
848 (1608639279900131335), 849 (1608639279900131336), ... (20 adds)]} 0 1
   [junit4]   2> 4313536 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=8725
   [junit4]   2> 4313537 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[860 
(1608639280110895104), 861 (1608639280111943680), 862 (1608639280111943681), 
863 (1608639280111943682), 864 (1608639280111943683), 865 
(1608639280111943684), 866 (1608639280111943685), 867 (1608639280111943686), 
868 (1608639280111943687), 869 (1608639280111943688), ... (20 adds)]} 0 0
   [junit4]   2> 4313738 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=8927
   [junit4]   2> 4313739 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[880 
(1608639280322707456), 881 (1608639280323756032), 882 (1608639280323756033), 
883 (1608639280323756034), 884 (1608639280323756035), 885 
(1608639280323756036), 886 (1608639280323756037), 887 (1608639280323756038), 
888 (1608639280323756039), 889 (1608639280323756040), ... (20 adds)]} 0 0
   [junit4]   2> 4313941 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=9130
   [junit4]   2> 4313942 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[900 
(1608639280536616960), 901 (1608639280536616961), 902 (1608639280536616962), 
903 (1608639280536616963), 904 (1608639280536616964), 905 
(1608639280536616965), 906 (1608639280536616966), 907 (1608639280536616967), 
908 (1608639280536616968), 909 (1608639280536616969), ... (20 adds)]} 0 0
   [junit4]   2> 4314151 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=9340
   [junit4]   2> 4314152 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[920 
(1608639280755769344), 921 (1608639280756817920), 922 (1608639280756817921), 
923 (1608639280756817922), 924 (1608639280756817923), 925 
(1608639280756817924), 926 (1608639280756817925), 927 (1608639280756817926), 
928 (1608639280756817927), 929 (1608639280756817928), ... (20 adds)]} 0 0
   [junit4]   2> 4314357 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=9546
   [junit4]   2> 4314358 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 
(1608639280972824576), 941 (1608639280972824577), 942 (1608639280972824578), 
943 (1608639280972824579), 944 (1608639280972824580), 945 
(1608639280972824581), 946 (1608639280972824582), 947 (1608639280972824583), 
948 (1608639280972824584), 949 (1608639280972824585), ... (20 adds)]} 0 0
   [junit4]   2> 4314573 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=9761
   [junit4]   2> 4314574 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 
(1608639281198268416), 961 (1608639281198268417), 962 (1608639281198268418), 
963 (1608639281198268419), 964 (1608639281198268420), 965 
(1608639281198268421), 966 (1608639281198268422), 967 (1608639281198268423), 
968 (1608639281198268424), 969 (1608639281198268425), ... (20 adds)]} 0 0
   [junit4]   2> 4314575 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4314575 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4314590 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2c6348ef[collection1] realtime]
   [junit4]   2> 4314591 INFO  (commitScheduler-11573-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4314637 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=9826
   [junit4]   2> 4314639 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 
(1608639281266425856), 981 (1608639281266425857), 982 (1608639281267474432), 
983 (1608639281267474433), 984 (1608639281267474434), 985 
(1608639281267474435), 986 (1608639281267474436), 987 (1608639281267474437), 
988 (1608639281267474438), 989 (1608639281267474439), ... (20 adds)]} 0 1
   [junit4]   2> 4314861 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=10050
   [junit4]   2> 4314861 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4314861 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
   [junit4]   2> 4314864 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6a0e3ac3[collection1] main]
   [junit4]   2> 4314898 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4314899 INFO  (searcherExecutor-11570-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a0e3ac3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):c140)
 Uninverting(_1(7.5.0):c140) Uninverting(_2(7.5.0):c140) 
Uninverting(_3(7.5.0):c140) Uninverting(_4(7.5.0):c140) 
Uninverting(_5(7.5.0):c140) Uninverting(_6(7.5.0):c140) 
Uninverting(_7(7.5.0):c20)))}
   [junit4]   2> 4314954 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
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=10143
   [junit4]   2> 4314958 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=975879506
   [junit4]   2> 4314970 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4314971 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5b04e783: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@23f15d75
   [junit4]   2> 4315010 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4315011 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@79aa9c15: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3637d942
   [junit4]   2> 4315053 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4315053 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6386733c: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@66cb95cb
   [junit4]   2> 4315087 INFO  (coreCloseExecutor-11575-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4326b6cf
   [junit4]   2> 4315087 INFO  (coreCloseExecutor-11575-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=4326b6cf
   [junit4]   2> 4315087 INFO  (coreCloseExecutor-11575-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5d8dc130: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1d8fa725
   [junit4]   2> 4315198 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4315380 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4315437 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4316038 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4317543 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4317543 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4317617 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4317653 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4317653 INFO  
(TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
   [junit4]   2> 4317665 INFO  (coreLoadExecutor-11585-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4317769 INFO  (coreLoadExecutor-11585-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4317827 INFO  (coreLoadExecutor-11585-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4318414 INFO  (coreLoadExecutor-11585-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 4318433 INFO  (coreLoadExecutor-11585-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-file

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

operty disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null218553652
     [copy] Copying 239 files to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null218553652
   [delete] Deleting directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null218553652

check-working-copy:
[ivy:cachepath] :: resolving dependencies :: 
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath]         confs: [default]
[ivy:cachepath]         found 
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath]         found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath]         found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath]         found org.apache.httpcomponents#httpclient;4.3.6 in 
public
[ivy:cachepath]         found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath]         found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath]         found commons-codec#commons-codec;1.6 in public
[ivy:cachepath]         found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 19ms :: artifacts dl 1ms
        ---------------------------------------------------------------------
        |                  |            modules            ||   artifacts   |
        |       conf       | number| search|dwnlded|evicted|| number|dwnlded|
        ---------------------------------------------------------------------
        |      default     |   8   |   0   |   0   |   0   ||   8   |   0   |
        ---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for 
further details.
[wc-checker] Checking working copy status...

-jenkins-base:

BUILD SUCCESSFUL
Total time: 194 minutes 19 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
        at hudson.FilePath$34.hasMatch(FilePath.java:2678)
        at hudson.FilePath$34.invoke(FilePath.java:2557)
        at hudson.FilePath$34.invoke(FilePath.java:2547)
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
                at 
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
                at 
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
                at hudson.remoting.Channel.call(Channel.java:955)
                at hudson.FilePath.act(FilePath.java:1036)
                at hudson.FilePath.act(FilePath.java:1025)
                at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
                at 
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
                at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
                at 
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
                at hudson.model.Build$BuildExecution.post2(Build.java:186)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
                at hudson.model.Run.execute(Run.java:1819)
                at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
                at 
hudson.model.ResourceController.execute(ResourceController.java:97)
                at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
        at hudson.remoting.UserRequest.perform(UserRequest.java:212)
        at hudson.remoting.UserRequest.perform(UserRequest.java:54)
        at hudson.remoting.Request$2.run(Request.java:369)
        at 
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no 
matches found within 10000
        at hudson.FilePath.act(FilePath.java:1038)
        at hudson.FilePath.act(FilePath.java:1025)
        at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
        at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
        at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
        at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
        at hudson.model.Build$BuildExecution.post2(Build.java:186)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
        at hudson.model.Run.execute(Run.java:1819)
        at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
        at hudson.model.ResourceController.execute(ResourceController.java:97)
        at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern 
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org