Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-repro/61/

[...truncated 30 lines...]
[repro] Jenkins log URL: 
https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.x/19//consoleText

[repro] Revision: 1dfd899a02526e2475a94dc448b212a65be65871

[repro] Ant options: -Dtests.multiplier=2 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt
[repro] Repro line:  ant test  -Dtestcase=DirectUpdateHandlerTest 
-Dtests.method=testPrepareCommit -Dtests.seed=CA067B073B987BAF 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt
 -Dtests.locale=lv -Dtests.timezone=Pacific/Palau -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[repro] Repro line:  ant test  -Dtestcase=StressHdfsTest -Dtests.method=test 
-Dtests.seed=CA067B073B987BAF -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt
 -Dtests.locale=th-TH -Dtests.timezone=America/Mazatlan -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[repro] git rev-parse --abbrev-ref HEAD
[repro] git rev-parse HEAD
[repro] Initial local git branch/revision: 
346dde33959396ada20473b690180b3bb2936cc7
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] git fetch
[repro] git checkout 1dfd899a02526e2475a94dc448b212a65be65871

[...truncated 2 lines...]
[repro] git merge --ff-only

[...truncated 1 lines...]
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       StressHdfsTest
[repro]       DirectUpdateHandlerTest
[repro] ant compile-test

[...truncated 3448 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 
-Dtests.class="*.StressHdfsTest|*.DirectUpdateHandlerTest" 
-Dtests.showOutput=onerror -Dtests.multiplier=2 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt
 -Dtests.seed=CA067B073B987BAF -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt
 -Dtests.locale=th-TH -Dtests.timezone=America/Mazatlan -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[...truncated 1571 lines...]
   [junit4]   2> 45397 ERROR (Finalizer) [     ] o.a.s.c.SolrCore REFCOUNT 
ERROR: unreferenced org.apache.solr.core.SolrCore@3e516ad0 (collection1) has a 
reference count of -1
   [junit4]   2> 45404 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 45437 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 45548 WARN  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Field text is not multivalued and destination for 
multiople copyFields (2)
   [junit4]   2> 45548 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 45563 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration 
from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 45564 INFO  (coreLoadExecutor-121-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@2ad8b2c6
   [junit4]   2> 45564 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-7-001/]
   [junit4]   2> 45651 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 45651 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 45655 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 45664 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 45705 INFO  (coreLoadExecutor-121-thread-1) [    x:collection1 
] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 45705 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 45706 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 45706 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 45706 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 45706 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 45706 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 45706 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 45707 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 45707 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 45707 INFO  
(SUITE-DirectUpdateHandlerTest-seed#[CA067B073B987BAF]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 45709 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45710 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testPrepareCommit
   [junit4]   2> 45711 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 45711 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 45722 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45723 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 45727 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[999]} 0 1
   [junit4]   2> 45748 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45748 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{optimize=} 0 21
   [junit4]   2> 45749 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 0
   [junit4]   2> 45750 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandlerTest FILES before addDoc=[_0.cfe, _0.cfs, _0.si, 
segments_3]
   [junit4]   2> 45751 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 45751 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandlerTest FILES before prepareCommit=[_0.cfe, _0.cfs, 
_0.si, _1.fdm, _1.fdt, _1_Lucene85FieldsIndex-doc_ids_2.tmp, 
_1_Lucene85FieldsIndexfile_pointers_3.tmp, segments_3]
   [junit4]   2> 45767 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={prepareCommit=true&wt=json&indent=true}{commit=} 0 15
   [junit4]   2> 45767 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandlerTest FILES after prepareCommit=[_0.cfe, _0.cfs, 
_0.si, _1.cfe, _1.cfs, _1.si, pending_segments_4, segments_3]
   [junit4]   2> 45767 INFO  
(TEST-DirectUpdateHandlerTest.testPrepareCommit-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ###Ending testPrepareCommit
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=DirectUpdateHandlerTest -Dtests.method=testPrepareCommit 
-Dtests.seed=CA067B073B987BAF -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true -Dtests.badapples=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt
 -Dtests.locale=th-TH -Dtests.timezone=America/Mazatlan -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.06s J5 | DirectUpdateHandlerTest.testPrepareCommit <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([CA067B073B987BAF:6ABBB435D2CAAFC6]:0)
   [junit4]    >        at 
org.apache.solr.update.DirectUpdateHandlerTest.testPrepareCommit(DirectUpdateHandlerTest.java:405)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 45772 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testBasics
   [junit4]   2> 45772 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 45772 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 45779 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45780 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 7
   [junit4]   2> 45781 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5]} 0 0
   [junit4]   2> 45782 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[6]} 0 0
   [junit4]   2> 45783 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 45784 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 45807 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 1 ms
   [junit4]   2> 45812 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 27
   [junit4]   2> 45813 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 45814 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 45814 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{delete=[5]} 0 0
   [junit4]   2> 45815 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 45832 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45833 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 45834 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 45834 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 45835 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL 
DOCUMENTS FROM INDEX
   [junit4]   2> 45836 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 45836 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 45848 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45849 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 45850 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 45851 INFO  
(TEST-DirectUpdateHandlerTest.testBasics-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testBasics
   [junit4] OK      0.08s J5 | DirectUpdateHandlerTest.testBasics
   [junit4]   2> 45855 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[     ] o.a.s.SolrTestCaseJ4 ###Starting testPostSoftCommitEvents
   [junit4]   2> 45855 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[     ] o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 45855 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 45868 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45868 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{commit=} 0 13
   [junit4]   2> 45870 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[999]} 0 1
   [junit4]   2> 45885 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45886 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{commit=} 0 15
   [junit4]   2> 45887 INFO  
(TEST-DirectUpdateHandlerTest.testPostSoftCommitEvents-seed#[CA067B073B987BAF]) 
[     ] o.a.s.SolrTestCaseJ4 ###Ending testPostSoftCommitEvents
   [junit4] OK      0.04s J5 | DirectUpdateHandlerTest.testPostSoftCommitEvents
   [junit4]   2> 45890 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
  ] o.a.s.SolrTestCaseJ4 ###Starting testRequireUniqueKey
   [junit4]   2> 45890 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
  ] o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 45890 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
  ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 45902 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45903 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
 x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 45904 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
 x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 1
   [junit4]   2> 45905 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
 x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{} 0 0
   [junit4]   2> 45905 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
 x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching 
ignore_exception
   [junit4]   2> 45905 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
 x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{} 0 0
   [junit4]   2> 45906 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
 x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching id
   [junit4]   2> 45906 INFO  
(TEST-DirectUpdateHandlerTest.testRequireUniqueKey-seed#[CA067B073B987BAF]) [   
  ] o.a.s.SolrTestCaseJ4 ###Ending testRequireUniqueKey
   [junit4] OK      0.02s J5 | DirectUpdateHandlerTest.testRequireUniqueKey
   [junit4]   2> 45909 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testDeleteRollback
   [junit4]   2> 45909 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 45910 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 45921 INFO  
(searcherExecutor-123-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 45922 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 45922 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 45922 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2001119628
   [junit4]   2> 45922 INFO  (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3d30ee33
   [junit4]   2> 45922 INFO  (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1 tag=SolrCore@3d30ee33
   [junit4]   2> 45923 INFO  (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@67642ad5: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1/com.codahale.metrics.MetricRegistry@4c8b0751
   [junit4]   2> 45953 INFO  (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED 
(unnecessary).
   [junit4]   2> 58072 ERROR (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.c.CachingDirectoryFactory Timeout waiting for all directory ref counts to 
be released - gave up waiting on 
CachedDir<<refCount=1;path=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-7-001/index;done=false>>
   [junit4]   2> 58073 ERROR (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.c.CachingDirectoryFactory Error closing 
directory:org.apache.solr.common.SolrException: Timeout waiting for all 
directory ref counts to be released - gave up waiting on 
CachedDir<<refCount=1;path=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-7-001/index;done=false>>
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:182)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.close(SolrCore.java:1689)
   [junit4]   2>        at 
org.apache.solr.core.SolrCores.lambda$close$0(SolrCores.java:138)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 58073 ERROR (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.c.SolrCore java.lang.AssertionError: 1
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:196)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.close(SolrCore.java:1689)
   [junit4]   2>        at 
org.apache.solr.core.SolrCores.lambda$close$0(SolrCores.java:138)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 58073 ERROR (coreCloseExecutor-128-thread-1) [     ] 
o.a.s.c.SolrCores Error shutting down core:java.lang.AssertionError: 1
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:196)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.close(SolrCore.java:1689)
   [junit4]   2>        at 
org.apache.solr.core.SolrCores.lambda$close$0(SolrCores.java:138)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 58073 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node 
tag=null
   [junit4]   2> 58073 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@56157630: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry 
solr.node/com.codahale.metrics.MetricRegistry@6b780d09
   [junit4]   2> 58081 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm 
tag=null
   [junit4]   2> 58081 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5b0f29fa: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry 
solr.jvm/com.codahale.metrics.MetricRegistry@520a9ace
   [junit4]   2> 58081 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 58081 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@54b04689: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty/com.codahale.metrics.MetricRegistry@36e35f94
   [junit4]   2> 58083 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 58093 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 58120 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-8-001
   [junit4]   2> 58125 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 58233 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.s.IndexSchema Field text is not multivalued and destination for 
multiople copyFields (2)
   [junit4]   2> 58234 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 58647 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.CoreContainer Unable to create 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/userfiles].
  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access 
denied ("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/userfiles"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied 
("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/userfiles"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
 ~[?:1.8.0_252]
   [junit4]   2>        at 
java.security.AccessController.checkPermission(AccessController.java:886) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkPermission(SecurityManager.java:549) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_252]
   [junit4]   2>        at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) 
~[?:?]
   [junit4]   2>        at 
sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376)
 ~[?:?]
   [junit4]   2>        at java.nio.file.Files.createDirectory(Files.java:674) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_252]
   [junit4]   2>        at 
java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_252]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:367) [java/:?]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:332) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:807) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:797) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:658) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:647) [java/:?]
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandlerTest.testDeleteRollback(DirectUpdateHandlerTest.java:282)
 [test/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_252]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_252]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
   [junit4]   2> 58649 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.f.DistribPackageStore Unable to create 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/filestore]
 directory in SOLR_HOME 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr].
  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access 
denied ("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/filestore"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied 
("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/filestore"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
 ~[?:1.8.0_252]
   [junit4]   2>        at 
java.security.AccessController.checkPermission(AccessController.java:886) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkPermission(SecurityManager.java:549) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_252]
   [junit4]   2>        at java.io.File.mkdir(File.java:1311) ~[?:1.8.0_252]
   [junit4]   2>        at java.io.File.mkdirs(File.java:1343) ~[?:1.8.0_252]
   [junit4]   2>        at 
org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561)
 [java/:?]
   [junit4]   2>        at 
org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81)
 [java/:?]
   [junit4]   2>        at 
org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) 
[java/:?]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.load(CoreContainer.java:668) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:171) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:807) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:797) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:658) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:647) [java/:?]
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandlerTest.testDeleteRollback(DirectUpdateHandlerTest.java:282)
 [test/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_252]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_252]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
   [junit4]   2> 58651 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 58652 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 58653 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@51305561[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 58654 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@51305561[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 58657 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 58658 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@53d514a1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 58658 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@53d514a1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 58659 WARN  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 58705 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad8b2c6
   [junit4]   2> 58705 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad8b2c6
   [junit4]   2> 58705 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad8b2c6
   [junit4]   2> 58707 INFO  (coreLoadExecutor-139-thread-1) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 58720 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 58752 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 58865 WARN  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Field text is not multivalued and destination for 
multiople copyFields (2)
   [junit4]   2> 58865 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 58880 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration 
from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 58880 INFO  (coreLoadExecutor-139-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@2ad8b2c6
   [junit4]   2> 58881 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-8-001/]
   [junit4]   2> 58963 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 58963 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 58968 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 58976 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 59017 INFO  (coreLoadExecutor-139-thread-1) [    x:collection1 
] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 59017 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 59017 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 59017 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 59017 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 59018 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 59018 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 59018 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 59019 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 59019 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 59019 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 59021 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[A]} 0 1
   [junit4]   2> 59021 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 59022 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[B]} 0 0
   [junit4]   2> 59047 INFO  (searcherExecutor-141-thread-1) [     ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 59049 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A+OR+id:B&indent=true} hits=2 status=0 QTime=1
   [junit4]   2> 59050 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{delete=[B]} 0 0
   [junit4]   2> 59051 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A+OR+id:B&indent=true} hits=2 status=0 QTime=2
   [junit4]   2> 59051 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandler2 start rollback{}
   [junit4]   2> 59054 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 59054 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandler2 end_rollback
   [junit4]   2> 59054 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A+OR+id:B&indent=true} hits=2 status=0 QTime=6
   [junit4]   2> 59056 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[ZZZ]} 0 0
   [junit4]   2> 59080 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 59080 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 23
   [junit4]   2> 59081 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:ZZZ&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 59082 INFO  
(TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ###Ending testDeleteRollback
   [junit4] OK      13.2s J5 | DirectUpdateHandlerTest.testDeleteRollback
   [junit4]   2> 59085 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testAddRollback
   [junit4]   2> 59085 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 59086 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 59096 INFO  
(searcherExecutor-141-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 59097 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 11
   [junit4]   2> 59097 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 59097 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=933453651
   [junit4]   2> 59098 INFO  (coreCloseExecutor-146-thread-1) [     ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@68ed6f75
   [junit4]   2> 59099 INFO  (coreCloseExecutor-146-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1 tag=SolrCore@68ed6f75
   [junit4]   2> 59099 INFO  (coreCloseExecutor-146-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4006243b: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1/com.codahale.metrics.MetricRegistry@21af47c
   [junit4]   2> 59120 INFO  (coreCloseExecutor-146-thread-1) [     ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED 
(unnecessary).
   [junit4]   2> 59122 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node 
tag=null
   [junit4]   2> 59122 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@309463b8: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry 
solr.node/com.codahale.metrics.MetricRegistry@7e752cca
   [junit4]   2> 59130 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm 
tag=null
   [junit4]   2> 59130 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6d555579: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry 
solr.jvm/com.codahale.metrics.MetricRegistry@520a9ace
   [junit4]   2> 59130 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 59130 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@e0c4155: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty/com.codahale.metrics.MetricRegistry@36e35f94
   [junit4]   2> 59132 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 59141 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 59168 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-9-001
   [junit4]   2> 59174 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 59284 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiople 
copyFields (2)
   [junit4]   2> 59284 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 60704 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.CoreContainer Unable to create 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/userfiles].
  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access 
denied ("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/userfiles"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied 
("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/userfiles"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
 ~[?:1.8.0_252]
   [junit4]   2>        at 
java.security.AccessController.checkPermission(AccessController.java:886) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkPermission(SecurityManager.java:549) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_252]
   [junit4]   2>        at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) 
~[?:?]
   [junit4]   2>        at 
sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376)
 ~[?:?]
   [junit4]   2>        at java.nio.file.Files.createDirectory(Files.java:674) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_252]
   [junit4]   2>        at 
java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_252]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:367) [java/:?]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:332) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:807) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:797) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:658) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:647) [java/:?]
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandlerTest.testAddRollback(DirectUpdateHandlerTest.java:223)
 [test/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_252]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_252]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
   [junit4]   2> 60706 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.f.DistribPackageStore Unable to create 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/filestore]
 directory in SOLR_HOME 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr].
  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access 
denied ("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/filestore"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied 
("java.io.FilePermission" 
"/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/filestore"
 "write")
   [junit4]   2>        at 
java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
 ~[?:1.8.0_252]
   [junit4]   2>        at 
java.security.AccessController.checkPermission(AccessController.java:886) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkPermission(SecurityManager.java:549) 
~[?:1.8.0_252]
   [junit4]   2>        at 
java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_252]
   [junit4]   2>        at java.io.File.mkdir(File.java:1311) ~[?:1.8.0_252]
   [junit4]   2>        at java.io.File.mkdirs(File.java:1343) ~[?:1.8.0_252]
   [junit4]   2>        at 
org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561)
 [java/:?]
   [junit4]   2>        at 
org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81)
 [java/:?]
   [junit4]   2>        at 
org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) 
[java/:?]
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.load(CoreContainer.java:668) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:171) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:807) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:797) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:658) [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:647) [java/:?]
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandlerTest.testAddRollback(DirectUpdateHandlerTest.java:223)
 [test/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_252]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_252]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
   [junit4]   2> 60708 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 60709 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 60710 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@48793d51[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 60710 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@48793d51[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 60714 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 60715 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@544ba15d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 60715 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@544ba15d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 60716 WARN  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 60763 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad8b2c6
   [junit4]   2> 60764 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad8b2c6
   [junit4]   2> 60764 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad8b2c6
   [junit4]   2> 60766 INFO  (coreLoadExecutor-157-thread-1) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 60777 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 60810 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 60917 WARN  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Field text is not multivalued and destination for 
multiople copyFields (2)
   [junit4]   2> 60917 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 60932 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration 
from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 60932 INFO  (coreLoadExecutor-157-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@2ad8b2c6
   [junit4]   2> 60933 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J5/temp/solr.update.DirectUpdateHandlerTest_CA067B073B987BAF-003/data-dir-9-001/]
   [junit4]   2> 61017 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 61017 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 61021 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 61029 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 61068 INFO  (coreLoadExecutor-157-thread-1) [    x:collection1 
] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 61069 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 61069 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 61069 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 61069 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 61069 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 61070 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 61070 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 61070 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 61071 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 61071 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 61073 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[A]} 0 1
   [junit4]   2> 61073 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 61095 INFO  (searcherExecutor-159-thread-1) [     ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 61096 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[B]} 0 0
   [junit4]   2> 61096 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.DirectUpdateHandler2 start rollback{}
   [junit4]   2> 61099 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 61099 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.u.DirectUpdateHandler2 end_rollback
   [junit4]   2> 61100 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A+OR+id:B&indent=true} hits=1 status=0 QTime=0
   [junit4]   2> 61101 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[ZZZ]} 0 0
   [junit4]   2> 61125 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 61126 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 23
   [junit4]   2> 61127 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:ZZZ&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 61127 INFO  
(TEST-DirectUpdateHandlerTest.testAddRollback-seed#[CA067B073B987BAF]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testAddRollback
   [junit4] OK      2.05s J5 | DirectUpdateHandlerTest.testAddRollback
   [junit4]   2> 61130 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testExpungeDeletes
   [junit4]   2> 61131 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [     
] o.a.s.u.DirectUpdateHandler2 [collection1]  REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 61131 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [     
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*}
 0 0
   [junit4]   2> 61142 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 61143 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 12
   [junit4]   2> 61144 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[1]} 0 0
   [junit4]   2> 61145 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[2]} 0 0
   [junit4]   2> 61169 INFO  
(searcherExecutor-159-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 61169 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 23
   [junit4]   2> 61170 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[3]} 0 0
   [junit4]   2> 61171 INFO  
(TEST-DirectUpdateHandlerTest.testExpungeDeletes-seed#[CA067B073B987BAF]) [    
x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collec

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

2>      at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 ~[java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
 ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        ... 17 more
   [junit4]   2> 24630 ERROR 
(SUITE-DirectUpdateHandlerTest-seed#[AB02D4152BC4AAA5]-worker) [     ] 
o.a.s.c.SolrCore Too many close [count:-1] on 
org.apache.solr.core.SolrCore@79a34731. Please report this exception to 
[email protected]
   [junit4]   2> 24630 ERROR 
(SUITE-DirectUpdateHandlerTest-seed#[AB02D4152BC4AAA5]-worker) [     ] 
o.a.s.c.u.ObjectReleaseTracker 
   [junit4]   2>           => java.lang.AssertionError: Too many closes on 
SolrCore
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.close(SolrCore.java:1560)
   [junit4]   2> java.lang.AssertionError: Too many closes on SolrCore
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.close(SolrCore.java:1560) ~[java/:?]
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.tryClose(ObjectReleaseTracker.java:85)
 [java/:?]
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:328) 
[java/:?]
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method) ~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 
~[?:1.8.0_252]
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 ~[?:1.8.0_252]
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498) 
~[?:1.8.0_252]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:901)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
 [java/:?]
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
 [java/:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
 [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_252]
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_AB02D4152BC4AAA5-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): 
{id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, 
maxPointsInLeafNode=1612, maxMBSortInHeap=5.888775331823259, 
sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=lv, 
timezone=Pacific/Palau
   [junit4]   2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 
1.8.0_252 (64-bit)/cpus=8,threads=1,free=333653592,total=493355008
   [junit4]   2> NOTE: All tests run in this JVM: [DirectUpdateHandlerTest]
   [junit4] Completed [5/5 (5!)] on J2 in 24.13s, 7 tests, 1 failure <<< 
FAILURES!
   [junit4] 
   [junit4] 
   [junit4] Tests with failures [seed: AB02D4152BC4AAA5]:
   [junit4]   - org.apache.solr.update.DirectUpdateHandlerTest.testPrepareCommit
   [junit4]   - org.apache.solr.update.DirectUpdateHandlerTest.testPrepareCommit
   [junit4]   - org.apache.solr.update.DirectUpdateHandlerTest.testPrepareCommit
   [junit4]   - org.apache.solr.update.DirectUpdateHandlerTest.testPrepareCommit
   [junit4]   - org.apache.solr.update.DirectUpdateHandlerTest.testPrepareCommit
   [junit4] 
   [junit4] 
   [junit4] JVM J0:     0.61 ..   805.34 =   804.73s
   [junit4] JVM J1:     0.62 ..   130.18 =   129.56s
   [junit4] JVM J2:     0.63 ..   923.38 =   922.75s
   [junit4] JVM J3:     0.63 ..   367.08 =   366.45s
   [junit4] JVM J4:     0.62 ..   700.65 =   700.03s
   [junit4] Execution time total: 15 minutes 23 seconds
   [junit4] Tests summary: 5 suites, 35 tests, 5 failures

BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/lucene/common-build.xml:1599:
 The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-repro/lucene/common-build.xml:1126:
 There were test failures: 5 suites, 35 tests, 5 failures [seed: 
AB02D4152BC4AAA5]

Total time: 15 minutes 25 seconds

[repro] Setting last failure code to 256

[repro] Failures at the tip of branch_8x without a seed:
[repro]   5/5 failed: org.apache.solr.update.DirectUpdateHandlerTest
[repro] git checkout 346dde33959396ada20473b690180b3bb2936cc7
Note: checking out '346dde33959396ada20473b690180b3bb2936cc7'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by performing another checkout.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -b with the checkout command again. Example:

  git checkout -b <new-branch-name>

HEAD is now at 346dde33959 LUCENE-9490: skip checkMissingDocsDefault on runtime 
Java > 14.
[repro] Exiting with code 256
Process leaked file descriptors. See 
https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for 
more information
Archiving artifacts
[Fast Archiver] No artifacts from Lucene » Lucene-Solr-repro 
Repro-Lucene/Lucene-Solr-Tests-8.x#71 to compare, so performing full copy of 
artifacts
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: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to